Sound issues

Sun Oct 19 12:40:47 UTC 2008

2008/10/19 Antonio M <antonio.montagnani at gmail.com>:
> 2008/10/19 Antonio M <antonio.montagnani at gmail.com>:
>> 2008/10/17 Antonio M <antonio.montagnani at gmail.com>:
>>> 2008/10/17 Antonio M <antonio.montagnani at gmail.com>:
>>>> 2008/10/17 Antonio Olivares <olivares14031 at yahoo.com>:
>>>>> --- On Fri, 10/17/08, Antonio M <antonio.montagnani at gmail.com> wrote:
>>>>>
>>>>>> From: Antonio M <antonio.montagnani at gmail.com>
>>>>>> Subject: Re: Sound issues
>>>>>> To: "For testers of Fedora Core development releases" <fedora-test-list at redhat.com>
>>>>>> Date: Friday, October 17, 2008, 4:39 AM
>>>>>> 2008/10/16 Antonio M <antonio.montagnani at gmail.com>:
>>>>>> > 2008/10/16 Christopher D. Stover
>>>>>> <quantumburnz at hotmail.com>:
>>>>>> >
>>>>>> >>
>>>>>> >> Antonio -- You said you have the same problem.  If
>>>>>> you do what I said and
>>>>>> >> type pulseaudio -k when it stops working, does
>>>>>> that fix the problem and
>>>>>> >> you're able to listen to things again?
>>>>>> I'm honestly not sure where to file
>>>>>> >> the problem either, but I feel it's probably a
>>>>>> pulseaudio problem.  I'm not
>>>>>> >> really sure what you meant by "...problem can
>>>>>> arise from the fact that
>>>>>> >> pulseaudio is in rawhide..."
>>>>>> >>
>>>>>> >> Chris
>>>>>> >>
>>>>>> >> --------------------------------------------------
>>>>>> >>
>>>>>> >
>>>>>> > as results are very different from kernel, I think
>>>>>> that kernel is the
>>>>>> > problem: after some trials I Have reverted to
>>>>>> 2.6.27-3.fc10.i686 and
>>>>>> > Rhytmbox has played for an hour then crashed...VLC
>>>>>> refuses to play
>>>>>> > same stream.
>>>>>> > Just to clarify my meaning, I am not satisfied with
>>>>>> multimedia (and
>>>>>> > things have not improved) in rawhide, sometimes I
>>>>>> think the problem is
>>>>>> > that you have pulseaudio, Rhythmbox from rawhide,
>>>>>> codecs from other
>>>>>> > parts, and there is no clear technical responsibility
>>>>>> of which package
>>>>>> > is involved, at least for me that I am a very
>>>>>> low-level tester: from
>>>>>> > the tester's point of view, it is difficult to
>>>>>> file a bug (against
>>>>>> > what??)....also problem is well known.
>>>>>> > And when you show a Fedora system to a friend of
>>>>>> yours, multimedia is
>>>>>> > important to check the quality of any OS (in many case
>>>>>> more than
>>>>>> > network management and so on...)
>>>>>> > --
>>>>>> > Antonio Montagnani
>>>>>> > Skype : antoniomontag
>>>>>> >
>>>>>> kernel 2.6.27.2-23.rc1.fc10.i686 is bad as many other
>>>>>> recent kernel,
>>>>>> with reference to multimedia.....my system is fully updated
>>>>>> with
>>>>>> reference also to RPMFusion.
>>>>>> VLC plays bad, Rhythmbox is suffering from many
>>>>>> interruptions....
>>>>>>
>>>>>> And still Multimedia is becoming important in the choice of
>>>>>> an OP,
>>>>>> shall I revert to Bill after eight years of RH/Fedora????
>>>>>> and rawhide
>>>>>> is not as raw as someone thinks, official release is not
>>>>>> very
>>>>>> far......
>>>>>>
>>>>>>
>>>>>> --
>>>>>> Antonio Montagnani
>>>>>> Skype : antoniomontag
>>>>>>
>>>>>> --
>>>>>
>>>>> If you compile say mplayer from source does it make a difference?
>>>>> I don't appear to have trouble playing multimedia files.  I have xine and mplayer compiled from source.  Don't give up on Fedora yet, report bugs complain and complain and if this does not make a difference(Nobody listens, answers to your bug reports), then you can go to Bill's software(OS).
>>>>>
>>>>> Regards,
>>>>>
>>>>> Antonio
>>>>>
>>>>> __________________________________________________
>>>>> Do You Yahoo!?
>>>>> Tired of spam?  Yahoo! Mail has the best spam protection around
>>>>> http://mail.yahoo.com
>>>>>
>>>>> --
>>>>> fedora-test-list mailing list
>>>>> fedora-test-list at redhat.com
>>>>> To unsubscribe:
>>>>> https://www.redhat.com/mailman/listinfo/fedora-test-list
>>>>>
>>>>
>>>> i have reverted to 2.6.27-0.370.rc8.fc10.i686 and issues have
>>>> disappeared (not sure, but at least Rhythmbox plays fine..for the time
>>>> being): I am reverting also to the laptop to same kernel.....I will
>>>> not file any other bug !!! sorry..as you know situation on multimedia
>>>> is poor(sound speed is well known, isn't it?) , especially if you have
>>>> an Intel chips, as I have both on desktop and laptop.
>>>> What is wrong between 2.6.27-0.370.rc8.fc10 and following kernels is
>>>> out of my skill, I am an humble tester(FHC=Fedora humble tester), not
>>>> a IT guru... :-(.
>>>>
>>>>
>>>> --
>>>> Antonio Montagnani
>>>> Skype : antoniomontag
>>>>
>>>
>>> and also VLC seems to be playing fine....
>>>
>>> --
>>> Antonio Montagnani
>>> Skype : antoniomontag
>>>
>>
>> grabbed latest kernel from koij and always same issues!!!!
>> 2.6.27.3-27.rc1.fc10.i686.
>> Music is too fast so buffer becomes empty in Rhythmbox (radio streaming).
>> Bug already filed.
>> I wonder when this problem will be solved.....for the time being at
>> the moment I will revert to older kernels, then I will see......
>> For your reference see
>> http://www.smolts.org/show?uuid=pub_2dabf29b-bd26-4825-b7ed-ac1db85fec24
>>
>> --
>> Antonio Montagnani
>> Skype : antoniomontag
>>
> Can somebody explain what is going on?? I run a test with pulseaudio
> -vvv and started Rhythmbox: when I got the buffer underrun in hythmbox
> I stopped the test....and this is the output...:If you look at the end
> of output you will see some rewinds and memblocks.Is this the
> problem???
>
>
> pulseaudio -k
> I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
> I: caps.c: Dropping root priviliges.
> I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
>
> [antonio at Casa ~]$ pulseaudio -vvv
> I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
> I: caps.c: Dropping root priviliges.
> I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
> D: main.c: Started as real root: no, suid root: yes
> I: main.c: PolicyKit grants us acquire-high-priority privilege.
> I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted
> I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
> I: core-util.c: Successfully gained nice level -11.
> I: main.c: Giving up CAP_NICE
> D: main.c: Can realtime: no, can high-priority: no
> I: main.c: This is PulseAudio 0.9.13
> D: main.c: Compilation host: i386-redhat-linux-gnu
> D: main.c: Compilation CFLAGS: -O2 -g -pipe -Wall
> -Wp,-D_FORTIFY_SOURCE=2 -fexceptions -fstack-protector
> --param=ssp-buffer-size=4 -m32 -march=i386 -mtune=generic
> -fasynchronous-unwind-tables -Wall -W -Wextra -pedantic -pipe
> -Wno-long-long -Wvla -Wno-overlength-strings -Wconversion -Wundef
> -Wformat -Wlogical-op -Wpacked -Wformat-security
> -Wmissing-include-dirs -Wformat-nonliteral -Wold-style-definition
> -Wdeclaration-after-statement -Wfloat-equal -Wmissing-declarations
> -Wmissing-prototypes -Wstrict-prototypes -Wredundant-decls
> -Wmissing-noreturn -Wshadow -Wendif-labels -Wpointer-arith
> -Wcast-align -Wwrite-strings -Wno-unused-parameter -ffast-math
> D: main.c: Running on host: Linux i686 2.6.27.3-27.rc1.fc10.i686 #1
> SMP Sat Oct 18 20:35:56 EDT 2008
> 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: Optimized build: yes
> I: main.c: Machine ID is 38929f7e08634e91811e590047369ba9.
> I: main.c: Using runtime directory
> /home/antonio/.pulse/38929f7e08634e91811e590047369ba9:runtime.
> I: main.c: Using state directory /home/antonio/.pulse.
> I: main.c: Running in system mode: no
> I: main.c: Fresh high-resolution timers available! Bon appetit!
> D: memblock.c: Using shared memory pool with 1024 slots of size 64,0
> KiB each, total size is 64,0 MiB
> D: cli-command.c: Checking for existance of
> '/usr/lib/pulse-0.9/modules/module-hal-detect.so': success
> I: module-hal-detect.c: Trying capability alsa
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/computer_alsa_timer
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/computer_alsa_sequencer
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/pci_8086_24d5_sound_card_0_alsa_playback_4
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/pci_8086_24d5_sound_card_0_alsa_capture_1
> D: module-hal-detect.c: Loading module-alsa-sink with arguments
> 'device_id=0 sink_name=alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0
> tsched=1'
> D: alsa-util.c: Trying front:0...
> D: alsa-util.c: Maximum hw buffer size is 371 ms
> I: module-alsa-sink.c: Successfully opened device front:0.
> I: module-alsa-sink.c: Successfully enabled mmap() mode.
> I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
> I: (alsa-lib)control.c: Invalid CTL front:0
> I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory
> I: alsa-util.c: Successfully attached to mixer 'hw:0'
> I: alsa-util.c: Using mixer control "Master".
> I: sink.c: Created sink 0
> "alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0" with sample
> spec s16le 2ch 44100Hz and channel map front-left,front-right
> I: source.c: Created source 0
> "alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0.monitor" with
> sample spec s16le 2ch 44100Hz and channel map front-left,front-right
> I: module-alsa-sink.c: Using 1 fragments of size 65536 bytes, buffer
> time is 371,52ms
> I: module-alsa-sink.c: Time scheduling watermark is 20,00ms
> D: module-alsa-sink.c: hwbuf_unused_frames=0
> D: module-alsa-sink.c: setting avail_min=62005
> I: module-alsa-sink.c: Volume ranges from 0 to 31.
> I: module-alsa-sink.c: Volume ranges from -46,50 dB to 0,00 dB.
> I: alsa-util.c: All 2 channels can be mapped to mixer channels.
> I: module-alsa-sink.c: Using hardware volume control. Hardware dB
> scale supported.
> D: alsa-util.c: snd_pcm_dump():
> D: alsa-util.c: Hardware PCM card 0 'Intel ICH5' device 0 subdevice 0
> 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     : 2
> D: alsa-util.c:   rate         : 44100
> D: alsa-util.c:   exact rate   : 44100 (44100/1)
> D: alsa-util.c:   msbits       : 16
> D: alsa-util.c:   buffer_size  : 16384
> D: alsa-util.c:   period_size  : 16384
> D: alsa-util.c:   period_time  : 371519
> D: alsa-util.c:   tstamp_mode  : NONE
> D: alsa-util.c:   period_step  : 1
> D: alsa-util.c:   avail_min    : 62005
> D: alsa-util.c:   period_event : 0
> D: alsa-util.c:   start_threshold  : -1
> D: alsa-util.c:   stop_threshold   : -1
> D: alsa-util.c:   silence_threshold: 0
> D: alsa-util.c:   silence_size : 0
> D: alsa-util.c:   boundary     : 1073741824
> D: module-alsa-sink.c: Read hardware volume: 0:  92% 1:  92%
> D: module-alsa-sink.c: Thread starting up
> D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
> I: module-alsa-sink.c: Starting playback.
> I: module.c: Loaded "module-alsa-sink" (index: #0; argument:
> "device_id=0 sink_name=alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0
> tsched=1").
> D: module-hal-detect.c: Loading module-alsa-source with arguments
> 'device_id=0 source_name=alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0
> tsched=1'
> D: alsa-util.c: Trying front:0...
> D: alsa-util.c: Maximum hw buffer size is 371 ms
> I: module-alsa-source.c: Successfully opened device front:0.
> I: module-alsa-source.c: Successfully enabled mmap() mode.
> I: module-alsa-source.c: Successfully enabled timer-based scheduling mode.
> I: (alsa-lib)control.c: Invalid CTL front:0
> I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory
> I: alsa-util.c: Successfully attached to mixer 'hw:0'
> I: alsa-util.c: Using mixer control "Capture".
> I: source.c: Created source 1
> "alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0" with sample
> spec s16le 2ch 44100Hz and channel map front-left,front-right
> I: module-alsa-source.c: Using 1 fragments of size 65536 bytes, buffer
> time is 371,52ms
> I: module-alsa-source.c: Time scheduling watermark is 20,00ms
> D: module-alsa-source.c: hwbuf_unused_frames=0
> D: module-alsa-source.c: setting avail_min=62005
> I: module-alsa-source.c: Volume ranges from 0 to 15.
> I: module-alsa-source.c: Volume ranges from 0,00 dB to 22,50 dB.
> I: alsa-util.c: All 2 channels can be mapped to mixer channels.
> I: module-alsa-source.c: Using hardware volume control. Hardware dB
> scale supported.
> D: alsa-util.c: snd_pcm_dump():
> D: alsa-util.c: Hardware PCM card 0 'Intel ICH5' device 0 subdevice 0
> D: alsa-util.c: Its setup is:
> D: alsa-util.c:   stream       : CAPTURE
> 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     : 2
> D: alsa-util.c:   rate         : 44100
> D: alsa-util.c:   exact rate   : 44100 (44100/1)
> D: alsa-util.c:   msbits       : 16
> D: alsa-util.c:   buffer_size  : 16384
> D: alsa-util.c:   period_size  : 16384
> D: alsa-util.c:   period_time  : 371519
> D: alsa-util.c:   tstamp_mode  : NONE
> D: alsa-util.c:   period_step  : 1
> D: alsa-util.c:   avail_min    : 62005
> D: alsa-util.c:   period_event : 0
> D: alsa-util.c:   start_threshold  : -1
> D: alsa-util.c:   stop_threshold   : -1
> D: alsa-util.c:   silence_threshold: 0
> D: alsa-util.c:   silence_size : 0
> D: alsa-util.c:   boundary     : 1073741824
> D: module-alsa-source.c: Read hardware volume: 0: 132% 1: 132%
> D: module-alsa-source.c: Thread starting up
> D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+28
> I: module.c: Loaded "module-alsa-source" (index: #1; argument:
> "device_id=0 source_name=alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0
> tsched=1").
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/pci_8086_24d5_sound_card_0_alsa_capture_3
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/pci_8086_24d5_sound_card_0_alsa_capture_2
> D: module-hal-detect.c: Not loaded device
> /org/freedesktop/Hal/devices/pci_8086_24d5_sound_card_0_alsa_control__1
> I: module-hal-detect.c: Loaded 2 modules.
> I: module.c: Loaded "module-hal-detect" (index: #2; argument: "").
> D: cli-command.c: Checking for existance of
> '/usr/lib/pulse-0.9/modules/module-esound-protocol-unix.so': success
> I: module.c: Loaded "module-esound-protocol-unix" (index: #3; argument: "").
> I: module.c: Loaded "module-native-protocol-unix" (index: #4; argument: "").
> I: module-stream-restore.c: Sucessfully opened database file
> '/home/antonio/.pulse/38929f7e08634e91811e590047369ba9:stream-volumes.i386-redhat-linux-gnu.gdbm'.
> I: module.c: Loaded "module-stream-restore" (index: #5; argument: "").
> I: module-device-restore.c: Sucessfully opened database file
> '/home/antonio/.pulse/38929f7e08634e91811e590047369ba9:device-volumes.i386-redhat-linux-gnu.gdbm'.
> I: module.c: Loaded "module-device-restore" (index: #6; argument: "").
> I: module-default-device-restore.c: Restored default sink
> 'alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0'.
> D: core-subscribe.c: Dropped redundant event due to change event.
> I: module-default-device-restore.c: Restored default source
> 'alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0'.
> 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: "").
> D: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 becomes idle.
> D: module-suspend-on-idle.c: Source
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0.monitor becomes
> idle.
> D: module-suspend-on-idle.c: Source
> alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0 becomes idle.
> I: module.c: Loaded "module-suspend-on-idle" (index: #10; argument: "").
> I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session2"
> D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session2
> I: module.c: Loaded "module-console-kit" (index: #11; argument: "").
> I: module.c: Loaded "module-position-event-sounds" (index: #12; argument: "").
> D: cli-command.c: Checking for existance of
> '/usr/lib/pulse-0.9/modules/module-gconf.so': success
> I: module.c: Loaded "module-gconf" (index: #13; argument: "").
> I: main.c: Daemon startup complete.
> D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus,
> path=/org/freedesktop/DBus, member=NameAcquired
> D: module-console-kit.c: dbus: interface=org.freedesktop.DBus,
> path=/org/freedesktop/DBus, member=NameAcquired
> I: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 idle for too
> long, suspending ...
> I: module-alsa-sink.c: Device suspended...
> I: module-suspend-on-idle.c: Source
> alsa_input.pci_8086_24d5_sound_card_0_alsa_capture_0 idle for too
> long, suspending ...
> I: module-alsa-source.c: Device suspended...
> I: module-suspend-on-idle.c: Source
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0.monitor idle
> for too long, suspending ...
> I: client.c: Created 1 "Native client (UNIX socket client)"
> D: protocol-native.c: Protocol version: remote 14, local 14
> I: protocol-native.c: Got credentials: uid=500 gid=500 success=1
> D: protocol-native.c: SHM possible: yes
> D: protocol-native.c: Negotiated SHM: yes
> I: module-stream-restore.c: Restoring device for stream
> sink-input-by-application-name:Rhythmbox.
> I: module-stream-restore.c: Restoring volume for sink input
> sink-input-by-application-name:Rhythmbox.
> D: module-stream-restore.c: Not restoring mute state for sink input
> sink-input-by-application-name:Rhythmbox, because already set.
> I: module-alsa-sink.c: Trying resume...
> D: alsa-util.c: Maximum hw buffer size is 371 ms
> D: module-alsa-sink.c: hwbuf_unused_frames=0
> D: module-alsa-sink.c: setting avail_min=62005
> I: module-alsa-sink.c: Resumed successfully...
> I: module-alsa-sink.c: Starting playback.
> D: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 becomes idle.
> D: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 becomes busy.
> I: resampler.c: Forcing resampler 'copy', because of fixed, identical
> sample rates.
> I: resampler.c: Using resampler 'copy'
> I: resampler.c: Using float32le as working format.
> 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 0 "Playback Stream" on
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 with sample
> spec s32le 2ch 44100Hz and channel map front-left,front-right
> I: protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms
> D: protocol-native.c: Traditional mode enabled, modifying sink usec
> only for compat with minreq.
> D: memblockq.c: memblockq requested: maxlength=141120, tlength=70560,
> base=8, prebuf=67032, minreq=3528 maxrewind=0
> D: memblockq.c: memblockq sanitized: maxlength=141120, tlength=70560,
> base=8, prebuf=67032, minreq=3528 maxrewind=0
> I: protocol-native.c: Final latency 380,00 ms = 180,00 ms + 2*10,00 ms
> + 180,00 ms
> D: module-alsa-sink.c: latency set to 180,00ms
> D: module-alsa-sink.c: hwbuf_unused_frames=8446
> D: module-alsa-sink.c: setting avail_min=36671
> D: module-alsa-sink.c: Requesting rewind due to latency change.
> D: module-alsa-sink.c: Requested to rewind 65536 bytes.
> D: module-alsa-sink.c: Limited to 61928 bytes.
> D: module-alsa-sink.c: before: 15482
> D: module-alsa-sink.c: after: 15482
> D: module-alsa-sink.c: Rewound 61928 bytes.
> D: sink.c: Processing rewind...
> D: sink-input.c: Have to rewind 61928 bytes on render memblockq.
> D: protocol-native.c: Requesting rewind due to end of underrun.
> D: module-alsa-sink.c: Requested to rewind 65536 bytes.
> D: module-alsa-sink.c: Limited to 21060 bytes.
> D: module-alsa-sink.c: before: 5265
> D: module-alsa-sink.c: after: 5265
> D: module-alsa-sink.c: Rewound 21060 bytes.
> D: sink.c: Processing rewind...
> D: sink-input.c: Have to rewind 21060 bytes on render memblockq.
> D: protocol-native.c: Requesting rewind due to end of underrun.
> D: module-alsa-sink.c: Requested to rewind 65536 bytes.
> D: module-alsa-sink.c: Limited to 20976 bytes.
> D: module-alsa-sink.c: before: 5244
> D: module-alsa-sink.c: after: 5244
> D: module-alsa-sink.c: Rewound 20976 bytes.
> D: sink.c: Processing rewind...
> D: sink-input.c: Have to rewind 20976 bytes on render memblockq.
> D: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 becomes idle.
> D: module-alsa-sink.c: hwbuf_unused_frames=0
> D: module-alsa-sink.c: setting avail_min=62005
> D: module-alsa-sink.c: Requested to rewind 65536 bytes.
> D: module-alsa-sink.c: Limited to 26584 bytes.
> D: module-alsa-sink.c: before: 6646
> D: module-alsa-sink.c: after: 6646
> D: module-alsa-sink.c: Rewound 26584 bytes.
> D: sink.c: Processing rewind...
> D: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 becomes idle.
> I: sink-input.c: Freeing input 0 "Rick Astley - Whenever You Need Somebody"
> I: client.c: Freed 1 "Rhythmbox"
> I: protocol-native.c: Connection died.
> I: module-suspend-on-idle.c: Sink
> alsa_output.pci_8086_24d5_sound_card_0_alsa_playback_0 idle for too
> long, suspending ...
> I: module-alsa-sink.c: Device suspended...
>
>
>
> --
> Antonio Montagnani
> Skype : antoniomontag
>

these are the last lines of a log after three interruptions...

 protocol-native.c: Final latency 380,00 ms = 180,00 ms + 2*10,00 ms + 180,00 ms
D: module-alsa-sink.c: latency set to 180,00ms
D: module-alsa-sink.c: hwbuf_unused_frames=8446
D: module-alsa-sink.c: setting avail_min=36671
D: module-alsa-sink.c: Requesting rewind due to latency change.
D: module-alsa-sink.c: Requested to rewind 65536 bytes.
D: module-alsa-sink.c: Limited to 61964 bytes.
D: module-alsa-sink.c: before: 15491
D: module-alsa-sink.c: after: 15491
D: module-alsa-sink.c: Rewound 61964 bytes.
D: sink.c: Processing rewind...

D: sink-input.c: Have to rewind 61964 bytes on render memblockq.
D: protocol-native.c: Requesting rewind due to end of underrun.
D: module-alsa-sink.c: Requested to rewind 65536 bytes.
D: module-alsa-sink.c: Limited to 27312 bytes.
D: module-alsa-sink.c: before: 6828
D: module-alsa-sink.c: after: 6828
D: module-alsa-sink.c: Rewound 27312 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 27312 bytes on render memblockq.

D: protocol-native.c: Requesting rewind due to end of underrun.
D: module-alsa-sink.c: Requested to rewind 65536 bytes.
D: module-alsa-sink.c: Limited to 22872 bytes.
D: module-alsa-sink.c: before: 5718
D: module-alsa-sink.c: after: 5718
D: module-alsa-sink.c: Rewound 22872 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 22872 bytes on render memblockq.

D: protocol-native.c: Requesting rewind due to end of underrun.
D: module-alsa-sink.c: Requested to rewind 65536 bytes.
D: module-alsa-sink.c: Limited to 19680 bytes.
D: module-alsa-sink.c: before: 4920
D: module-alsa-sink.c: after: 4920
D: module-alsa-sink.c: Rewound 19680 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 19680 bytes on render memblockq.

-- 
Antonio Montagnani
Skype : antoniomontag