Comment 6 for bug 660567

My bad, I didn't enable log-time. I have now and here is the log attached, and again the test program output interspersed with the log is as follows:

...
( 0.034| 0.000) D: source.c: Processing rewind...
[0.344269] play done
[0.344328] not calling pa_simple_drain()
[0.358380] playing 680 hz tone volume 50 for 500 ms
[0.829451] play done
[0.829489] not calling pa_simple_drain()
[0.840411] playing 440 hz tone volume 50 for 500 ms
[1.303775] play done
( 1.471| 1.437) D: protocol-native.c: Underrun on 'beep', 0 bytes in queue.
[3.505551] pa_simple_drain() done
[3.519491] playing 680 hz tone volume 50 for 500 ms
( 3.532| 2.060) D: protocol-native.c: Requesting rewind due to end of underrun.
( 3.532| 0.000) D: alsa-sink.c: Requested to rewind 10156 bytes.
( 3.532| 0.000) D: alsa-sink.c: Limited to 10156 bytes.
( 3.532| 0.000) D: alsa-sink.c: before: 2539
( 3.532| 0.000) D: alsa-sink.c: after: 2539
( 3.532| 0.000) D: alsa-sink.c: Rewound 10156 bytes.
( 3.532| 0.000) D: sink.c: Processing rewind...
( 3.532| 0.000) D: sink-input.c: Have to rewind 10156 bytes on render memblockq.
( 3.533| 0.000) D: source.c: Processing rewind...
[3.848197] play done
( 4.016| 0.483) D: protocol-native.c: Underrun on 'beep', 0 bytes in queue.
[6.050165] pa_simple_drain() done
[6.062775] playing 440 hz tone volume 50 for 500 ms
...