Re: [xine-devel] xine and stuttering ALSA playback

SourceForge Headquarters 225 Broadway Suite 1600 San Diego, CA 92101 +1 (858) 454-5900

Thus spake Markus Korber:

> Thus spake Markus Korber:
>
>> I've got some serious trouble with xine-lib and ALSA playback.  I'm
>> using a uclibc (0.9.29) based ARM toolchain (gcc-4.2.1).  I've tested
>> with alsa-lib 1.0.13, 1.0.14a, 1.0.15rc1.
>
>> However, all I get is stuttering sound playback from ALSA.  Although,
>> aplay, alsaplayer, and mplayer work fine.
>
> I've now compiled alsa-lib-1.0.15rc1, xine-lib-1.1.7, and xine-ui-0.99.5
> on my PC and the sound also stutters:

Ok there seems to be a alsa-lib problem on my PC introduced between
1.0.14rc4 and 1.0.14.  However, enabling debug logs in 'audio_out.c' on
the ARM target reveals that a lot of audio frames get dropped due to
large vpts gaps.

,----
| [root@(none) ~]# fbxine sine440stereo.wav 
| audio_out: (ao_update_av_sync_method:1981) av_sync_method = 0
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_update_resample_factor:1365) audio_step 66873 pts per 32768 frames
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=0, vpts=121925, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=2089, vpts=124014, flushmode=0
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 110356
| audio_out: (ao_loop:1124) hw_vpts : 110356 buffer_vpts : 121925 gap : 11569
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=4179, vpts=126103, flushmode=0
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 1024, current time is 110900
| audio_out: (ao_loop:1124) hw_vpts : 112992 buffer_vpts : 124014 gap : 11022
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=6269, vpts=128193, flushmode=0
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=8359, vpts=130283, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=10448, vpts=132373, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=12538, vpts=134462, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=14628, vpts=136552, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=16718, vpts=138642, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=18808, vpts=140732, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=20897, vpts=142822, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=22987, vpts=144911, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=25077, vpts=147001, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=27167, vpts=149091, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=29257, vpts=151181, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| current delay is 2048, current time is 111382
| audio_out: (ao_loop:1124) hw_vpts : 115567 buffer_vpts : 126103 gap : 10536
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=31346, vpts=153270, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 3072, current time is 112820
| audio_out: (ao_loop:1124) hw_vpts : 119098 buffer_vpts : 128193 gap : 9095
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=33436, vpts=155360, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 4096, current time is 113343
| audio_out: (ao_loop:1124) hw_vpts : 121714 buffer_vpts : 130283 gap : 8569
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=35526, vpts=157450, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=37616, vpts=159540, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=39706, vpts=161630, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=41795, vpts=163719, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=43885, vpts=165809, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=45975, vpts=167899, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=48065, vpts=169989, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=50155, vpts=172078, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=52244, vpts=174168, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=54334, vpts=176258, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=56424, vpts=178348, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=58514, vpts=180438, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=60604, vpts=182527, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=62693, vpts=184617, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=64783, vpts=186707, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=66873, vpts=188797, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=68963, vpts=190887, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=71053, vpts=192976, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=73142, vpts=195066, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 145858
| audio_out: (ao_loop:1124) hw_vpts : 145858 buffer_vpts : 132373 gap : -13485
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=75232, vpts=197156, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 1024, current time is 146441
| audio_out: (ao_loop:1124) hw_vpts : 148533 buffer_vpts : 134462 gap : -14071
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 2048, current time is 146883
| audio_out: (ao_loop:1124) hw_vpts : 151068 buffer_vpts : 136552 gap : -14516
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 3072, current time is 147187
| audio_out: (ao_loop:1124) hw_vpts : 153465 buffer_vpts : 138642 gap : -14823
| audio_out: (prepare_samples:729) outputting 1024 frames
| audio_out: (ao_loop:1202) loop: writing 1024 samples to sound device
| audio_out: (ao_loop:1236) loop: next buf from fifo
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 4096, current time is 147557
| audio_out: (ao_loop:1124) hw_vpts : 155928 buffer_vpts : 140732 gap : -15196
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 140732, gap = -15196) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 177812
| audio_out: (ao_loop:1124) hw_vpts : 177812 buffer_vpts : 142822 gap : -34990
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 142822, gap = -34990) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 177959
| audio_out: (ao_loop:1124) hw_vpts : 177959 buffer_vpts : 144911 gap : -33048
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 144911, gap = -33048) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 178175
| audio_out: (ao_loop:1124) hw_vpts : 178175 buffer_vpts : 147001 gap : -31174
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 147001, gap = -31174) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 178322
| audio_out: (ao_loop:1124) hw_vpts : 178322 buffer_vpts : 149091 gap : -29231
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 149091, gap = -29231) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 178474
| audio_out: (ao_loop:1124) hw_vpts : 178474 buffer_vpts : 151181 gap : -27293
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 151181, gap = -27293) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 178617
| audio_out: (ao_loop:1124) hw_vpts : 178617 buffer_vpts : 153270 gap : -25347
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 153270, gap = -25347) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 178762
| audio_out: (ao_loop:1124) hw_vpts : 178762 buffer_vpts : 155360 gap : -23402
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 155360, gap = -23402) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=77322, vpts=199246, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=79412, vpts=201335, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=81502, vpts=203425, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=83591, vpts=205515, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=85681, vpts=207605, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=87771, vpts=209695, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=89861, vpts=211784, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=91951, vpts=213874, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=94040, vpts=215964, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=96130, vpts=218054, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=98220, vpts=220143, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_put_buffer:1542) ao_put_buffer, pts=100310, vpts=222233, flushmode=0
| audio_out: (ao_put_buffer:1553) ao_put_buffer done
| audio_out: (ao_loop:1110) current delay is 0, current time is 178907
| audio_out: (ao_loop:1124) hw_vpts : 178907 buffer_vpts : 157450 gap : -21457
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 157450, gap = -21457) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 210244
| audio_out: (ao_loop:1124) hw_vpts : 210244 buffer_vpts : 159540 gap : -50704
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 159540, gap = -50704) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 210394
| audio_out: (ao_loop:1124) hw_vpts : 210394 buffer_vpts : 161630 gap : -48764
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 161630, gap = -48764) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 210652
| audio_out: (ao_loop:1124) hw_vpts : 210652 buffer_vpts : 163719 gap : -46933
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 163719, gap = -46933) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 210798
| audio_out: (ao_loop:1124) hw_vpts : 210798 buffer_vpts : 165809 gap : -44989
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 165809, gap = -44989) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 210947
| audio_out: (ao_loop:1124) hw_vpts : 210947 buffer_vpts : 167899 gap : -43048
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 167899, gap = -43048) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 211091
| audio_out: (ao_loop:1124) hw_vpts : 211091 buffer_vpts : 169989 gap : -41102
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 169989, gap = -41102) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 211234
| audio_out: (ao_loop:1124) hw_vpts : 211234 buffer_vpts : 172078 gap : -39156
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 172078, gap = -39156) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 211442
| audio_out: (ao_loop:1124) hw_vpts : 211442 buffer_vpts : 174168 gap : -37274
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 174168, gap = -37274) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 211589
| audio_out: (ao_loop:1124) hw_vpts : 211589 buffer_vpts : 176258 gap : -35331
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 176258, gap = -35331) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 241816
| audio_out: (ao_loop:1124) hw_vpts : 241816 buffer_vpts : 178348 gap : -63468
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 178348, gap = -63468) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242036
| audio_out: (ao_loop:1124) hw_vpts : 242036 buffer_vpts : 180438 gap : -61598
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 180438, gap = -61598) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242182
| audio_out: (ao_loop:1124) hw_vpts : 242182 buffer_vpts : 182527 gap : -59655
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 182527, gap = -59655) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242325
| audio_out: (ao_loop:1124) hw_vpts : 242325 buffer_vpts : 184617 gap : -57708
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 184617, gap = -57708) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242472
| audio_out: (ao_loop:1124) hw_vpts : 242472 buffer_vpts : 186707 gap : -55765
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 186707, gap = -55765) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242616
| audio_out: (ao_loop:1124) hw_vpts : 242616 buffer_vpts : 188797 gap : -53819
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 188797, gap = -53819) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242758
| audio_out: (ao_loop:1124) hw_vpts : 242758 buffer_vpts : 190887 gap : -51871
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 190887, gap = -51871) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| audio_out: (ao_loop:1110) current delay is 0, current time is 242973
| audio_out: (ao_loop:1124) hw_vpts : 242973 buffer_vpts : 192976 gap : -49997
| audio_out: (ao_loop:1144) loop: drop package, next fifo
| audio_out: (ao_loop:1150) audio package (vpts = 192976, gap = -49997) dropped
| audio_out: (ao_loop:981) loop: get buf from fifo
| audio_out: (ao_loop:984) got a buffer
| [...]
`----

Regards,
Markus Korber