VLC Loses Audio Sync Over Time Progressive

[sylar108]

Hey,

Whenever I play a video with VLC, it will lose audio sync progressively. It takes about 20 minutes for it to be noticable. After 35-40 minutes, the sync is off by over 1.5 seconds. It will continue to get worse progressively. This happens on every file regardless of type. If you reload the file, seek, or even pause/restart it will sync back perfectly.

My Specs:
Abit IP35 Pro onboard adio
NVidia 8800GTX
Q6700 3.0GHZ (Stock clocking doesn't fix issue)
Logitech Z-5500 5.1 Speakers
Connected via SPDIF
Windows 7 Ultimate x64 w/ All Updates

VLC 1.0.5 Goldeneye
Checked Use SPDIF when avalible
Waveout: Use float32, Realtek Digital Output selected
File Audio Output: spdif
Audio Output Module: Win32 waveout extension output
Everything else is default settings


---------

Please help! I have searched everywhere and it appears that I am the only one who has this problem! I used K Lite Mega Codec Pack w/ MPC for years and this seems lightyears better as it is one program not 300 but this is driving me nuts!!! All I want is a media player that will play any common file format and directly output the sound through SPDIF without modifying it!!!

Thanks,
Stephen

[Jean-Baptiste Kempf]

Does it happen with all audio files and all audio outputs.

[sylar108]

I tried using a 3.5mm plug to the direct input on the Z5500. I had to change it from "Win32 waveout extension output" to "Default". It does not lose sync now but I want to use SPDIF.

SPDIF loses sync on all files. It does not lose sync on any files with WMP.

Any ideas?

[VLC_help]

Could you try VLC 1.1.0 nightly builds?

[sylar108]

The nightly builds didnt help. Any new suggestions?

[VLC_help]

Update audio device drivers.

[xad001x0w]

Hi there,

I have exactly the same problem, I use VLC to play DVDs, AVIs, and MKVs mainly and I experience exactly the same problem as you.

In fact, I created a thread about this some time ago but the problem was never resolved. This has even been going on for so long that I have even gotten a new system in that time yet the problem persists. The only constant between them being the sound card and speaker system. Always 20min mark to become noticeable then progressively worse from them happens when using SPDIF or analogue outs.

Old system:
Pentium 4 3GHz
2 GB RAM
Nvidia 7800GTX
Asus Xonar D2/PM (PCI)
Logitech Z5500
XP Pro

New system:
Core I7 2.66GHz
12GB RAM
Nvidia 275GTX
Asus Xonar D2/PM (PCI)
Logitech Z5500
XP 64

I can only see that the issue stems from my sound-card or VLC as these issues only arise in VLC and no other media player and as I have literally tried everything else; latest drivers on _everything_, latest and nightly builds of VLC. etc

[VLC_help]

Does VLC log (Tools -> Messages... set Verbosity to 2) complain about sync issues when this happens?

[xad001x0w]

No, it churns out lots of stuff when loading the file but nothing else.

This was about a 43 minute avi file which started going out of sync around the 21/22 minute mark. Please excuse the stupidly long amount of stuff to paste into the code section but I wasn't sure what would be useful.

Code: Select all

main debug: adding item `zzzzzzzzzzzzzzzzzzz.avi' ( z:\zzzzz\zzzzz\zzzzzzzzz\zzzzzzzzzzzzzzzzzzz.avi ) main debug: control type=0 main debug: dying input main debug: control: stopping input main debug: dying input main debug: thread times: real 0m21.562500s, kernel 0m0.000000s, user 0m0.000000s avcodec debug: ffmpeg codec (MPEG-4 Video) stopped main debug: removing module "avcodec" main debug: killing decoder fourcc `XVID', 0 PES in FIFO main debug: [0] 2 0 main debug: [1] 4 0 main debug: [2] 4 0 main debug: [3] 2 0 main debug: [4] 0 0 main debug: [5] 0 0 main debug: [6] 0 0 main debug: [7] 2 0 main debug: [8] 4 0 main debug: [9] 2 0 main debug: [10] 2 0 main debug: [11] 2 0 main debug: [12] 2 0 main debug: [13] 2 0 main debug: [14] 1 0 main debug: [15] 2 0 main debug: saving a free vout main debug: thread times: real 0m21.562500s, kernel 0m0.062500s, user 0m0.000000s main debug: removing module "mpeg_audio" main debug: killing decoder fourcc `mpga', 0 PES in FIFO main debug: removing module "mpgatofixed32" main debug: removing module "bandlimited_resampler" main debug: thread ended main debug: thread times: real 0m21.578125s, kernel 0m0.000000s, user 0m0.000000s main debug: removing module "waveout" main debug: removing module "converter_float" main debug: removing module "float32_mixer" main debug: releasing aout avi debug: free chunk avih avi debug: free chunk strh avi debug: free chunk strf avi debug: free chunk LIST avi debug: free chunk strh avi debug: free chunk strf avi debug: free chunk LIST avi debug: free chunk LIST avi debug: free chunk ISFT avi debug: free chunk LIST avi debug: free chunk JUNK avi debug: free chunk LIST avi debug: free chunk idx1 avi debug: free chunk RIFF avi debug: free chunk LIST main debug: removing module "avi" main debug: removing module "stream_filter_record" main debug: removing module "access_file" main debug: Program doesn't contain anymore ES main debug: thread ended main debug: dead input main debug: thread times: real 0m21.671875s, kernel 0m0.015625s, user 0m0.046875s main debug: processing request item zzzzzzzzzzz.avi node Playlist skip 0 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 2 items, index 1 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'zzzzzzzzzzzzzzzzzzz.avi' main debug: thread (input) created at priority 1 (../.././src/input/input.c:230) main debug: thread started main debug: selected audio language[0] en main debug: selected subtitle language[0] en main debug: using timeshift granularity of 50 MBytes main debug: using timeshift path 'C:\DOCUME~1\zzzzz\LOCALS~1\Temp' main debug: `z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' gives access `' demux `' path `z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' main debug: creating demux: access='' demux='' path='z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' main debug: looking for access_demux module: 3 candidates qt4 debug: IM: Deleting the input qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Qt: Entering Fullscreen main debug: TIMER input launching for 'S05E18 - Great Space Roaster.avi' : 31.000 ms - Total 31.000 ms / 1 intvls (Avg 31.000 ms) qt4 debug: IM: Setting an input qt4 debug: Updating the geometry qt4 debug: Updating the geometry main debug: TIMER module_need() : 26.000 ms - Total 26.000 ms / 1 intvls (Avg 26.000 ms) main debug: creating access '' path='z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' main debug: looking for access module: 5 candidates vcd debug: trying .cue file: z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.cue vcd debug: could not find .cue file access_file debug: opening file `z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' main debug: using access module "access_file" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: Using AStream*Stream main debug: pre buffering main debug: received first data after 0 ms main debug: pre-buffering done 1024 bytes in 0s - 1000000 kbytes/s main debug: looking for stream_filter module: 2 candidates main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating demux: access='' demux='' path='z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' main debug: looking for demux module: 50 candidates avi debug: found Chunk fourcc:46464952 (RIFF) size:367040896 pos:0 avi debug: found LIST chunk: 'AVI ' avi debug: <list 'AVI '> avi debug: found Chunk fourcc:5453494c (LIST) size:306 pos:12 avi debug: found LIST chunk: 'hdrl' avi debug: <list 'hdrl'> avi debug: found Chunk fourcc:68697661 (avih) size:56 pos:24 avi debug: avih: streams:2 flags: HAS_INDEX IS_INTERLEAVED 624x352 avi debug: found Chunk fourcc:5453494c (LIST) size:116 pos:88 avi debug: found LIST chunk: 'strl' avi debug: <list 'strl'> avi debug: found Chunk fourcc:68727473 (strh) size:56 pos:100 avi debug: strh: type:vids handler:0x44495658 samplesize:0 23.98fps avi debug: found Chunk fourcc:66727473 (strf) size:40 pos:164 avi debug: strf: video:XVID 624x352 planes:1 24bpp avi debug: </list 'strl'> avi debug: found Chunk fourcc:5453494c (LIST) size:106 pos:212 avi debug: found LIST chunk: 'strl' avi debug: <list 'strl'> avi debug: found Chunk fourcc:68727473 (strh) size:56 pos:224 avi debug: strh: type:auds handler:0x00000000 samplesize:0 41.67fps avi debug: found Chunk fourcc:66727473 (strf) size:30 pos:288 avi debug: strf: audio:0x0055 channels:2 48000Hz 16bits/sample 125kb/s avi debug: </list 'strl'> avi debug: </list 'hdrl'> avi debug: found Chunk fourcc:5453494c (LIST) size:28 pos:326 avi debug: found LIST chunk: 'INFO' avi debug: <list 'INFO'> avi debug: found Chunk fourcc:54465349 (ISFT) size:16 pos:338 avi debug: ISFT: software : transcode-1.0.4 avi debug: </list 'INFO'> avi debug: found Chunk fourcc:4b4e554a (JUNK) size:1666 pos:362 avi debug: found Chunk fourcc:5453494c (LIST) size:364320884 pos:2036 avi debug: skipping movi chunk avi debug: found Chunk fourcc:31786469 (idx1) size:2717968 pos:364322928 avi debug: idx1: index entry:169873 avi debug: </list 'AVI '> avi debug: * LIST-root size:367040904 pos:0 avi debug: + RIFF-AVI size:367040896 pos:0 avi debug: | + LIST-hdrl size:306 pos:12 avi debug: | | + avih size:56 pos:24 avi debug: | | + LIST-strl size:116 pos:88 avi debug: | | | + strh size:56 pos:100 avi debug: | | | + strf size:40 pos:164 avi debug: | | + LIST-strl size:106 pos:212 avi debug: | | | + strh size:56 pos:224 avi debug: | | | + strf size:30 pos:288 avi debug: | + LIST-INFO size:28 pos:326 avi debug: | | + ISFT size:16 pos:338 avi debug: | + JUNK size:1666 pos:362 avi debug: | + LIST-movi size:364320884 pos:2036 avi debug: | + idx1 size:2717968 pos:364322928 avi debug: AVIH: 2 stream, flags HAS_INDEX IS_INTERLEAVED avi debug: stream[0] rate:23976024 scale:1000000 samplesize:0 avi debug: stream[0] video(XVID) 624x352 24bpp 23.976024fps main debug: selecting program id=0 avi debug: stream[1] rate:48000 scale:1152 samplesize:0 avi debug: stream[1] audio(0x55) 2 channels 48000Hz 16bits avi debug: stream[0] created 62046 index entries avi debug: stream[1] created 107827 index entries avi debug: stream[0] length:2587 (based on index) avi debug: stream[1] length:2587 (based on index) main debug: using demux module "avi" main debug: TIMER module_need() : 106.000 ms - Total 106.000 ms / 1 intvls (Avg 106.000 ms) main debug: looking for a subtitle file in z:\zzzzzz\zzzzz\zzzzzzzz\ main debug: looking for decoder module: 35 candidates avcodec debug: libavcodec already initialized avcodec debug: using direct rendering avcodec debug: ffmpeg codec (MPEG-4 Video) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg 2.000 ms) main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315) main debug: thread started main debug: looking for decoder module: 35 candidates main debug: using decoder module "mpeg_audio" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315) main debug: thread started main debug: `z:\zzzzz\zzzzzzzz\zzzzzzz\zzzzzzzzzzzzzzzz.avi' successfully opened main debug: Buffering 0% main debug: Buffering 8% main debug: Buffering 16% mpeg_audio debug: MPGA channels:2 samplerate:48000 bitrate:128 main debug: Buffering 25% main debug: Buffering 33% main debug: Buffering 41% main debug: Buffering 50% main debug: Buffering 58% main debug: Buffering 66% main debug: Buffering 75% main debug: Buffering 83% main debug: Buffering 91% main debug: Buffering 100% main debug: reusing aout main debug: Stream buffering done (325 ms in 0 ms) main debug: trying to reuse free vout main debug: reusing provided vout main debug: looking for audio output module: 3 candidates waveout debug: Drivername: ASUS Xonar D2 Audio waveout debug: Driver Version: 5.10 waveout debug: Manufacturer identifier: 0x1 waveout debug: Product identifier: 0x64 waveout debug: device supports 2 channels waveout debug: device supports 1 channel waveout debug: OpenWaveDevice-ID: 0 waveout debug: waveformat.Format.cbSize = 0 waveout debug: waveformat.Format.wFormatTag = 1 waveout debug: waveformat.Format.nChannels = 2 waveout debug: waveformat.Format.nSamplesPerSec = 48000 waveout debug: waveformat.Format.nAvgBytesPerSec = 192000 waveout debug: waveformat.Format.nBlockAlign = 4 waveout debug: waveformat.Format.wBitsPerSample = 16 waveout debug: waveformat.Samples.wValidBitsPerSample = 16 waveout debug: waveformat.Samples.wSamplesPerBlock = 16 waveout debug: waveformat.dwChannelMask = 3 main debug: End of video preroll main debug: Received first picture qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Updating the geometry qt4 debug: Qt: Entering Fullscreen main debug: thread (waveOut Notification Thread) created at priority 1 (../../.././modules/audio_output/waveout.c:436) main debug: using audio output module "waveout" main debug: TIMER module_need() : 40.000 ms - Total 40.000 ms / 1 intvls (Avg 40.000 ms) main debug: thread started main debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes main debug: mixer 'fl32' 48000 Hz Stereo frame=1 samples/8 bytes main debug: filter(s) 'fl32'->'s16l' 48000 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "converter_float" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: looking for audio mixer module: 3 candidates main debug: using audio mixer module "float32_mixer" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: input 'mpga' 48000 Hz Stereo frame=1152 samples/969 bytes main debug: filter(s) 'mpga'->'fl32' 48000 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "mpgatofixed32" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: filter(s) 'fl32'->'fl32' 52800 Hz->48000 Hz Stereo->Stereo main debug: looking for audio filter module: 24 candidates main debug: using audio filter module "bandlimited_resampler" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: found a filter for the whole conversion main debug: End of audio preroll main debug: Decoder buffering done in 42 ms main warning: PTS is out of range (-10000), dropping buffer main warning: PTS is out of range (-34000), dropping buffer mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer waveout debug: Wakeup sleeping output thread. mpgatofixed32 debug: libmad error: bad main_data_begin pointer waveout debug: will start to play in 48000 us mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer mpgatofixed32 debug: libmad error: bad main_data_begin pointer direct3d debug: entering fullscreen mode qt4 debug: Qt4: Fullscreen state changed qt4 debug: Qt: Entering Fullscreen direct3d debug: leaving fullscreen mode qt4 debug: Qt4: Fullscreen state changed qt4 debug: Qt: Entering Fullscreen

[VLC_help]

xad001x0w: same happens with DirectX audio output module?

[xad001x0w]

Yes the audio is in sync, however the log occasionally spits out a few warnings like those below (same source file as before):

Code: Select all

main warning: output date isn't PTS date, requesting resampling (-40334) main warning: buffer is 40334 in advance, triggering downsampling main warning: resampling stopped after 9649000 usec (drift: -541)

However any DTS or DD etc encoded files fail to play properly when using A/52 device and directx out. ie using the decoder in my speaker system.
churns out loads of lines like this:

Code: Select all

main debug: audio output is too slow (217000), trashing 32000us main debug: audio output is too slow (185000), trashing 32000us main debug: audio output is too slow (153000), trashing 32000us main debug: audio output is too slow (121000), trashing 32000us main debug: audio output is too slow (89000), trashing 32000us main debug: audio output is too slow (57000), trashing 32000us main debug: audio output is too slow (-7000), trashing 32000us main debug: audio output is too slow (-103000), trashing 32000us main debug: audio output is too slow (-199000), trashing 32000us main debug: audio output is too slow (-295000), trashing 32000us main debug: audio output is too slow (641000), trashing 32000us

[xad001x0w]

Any reply to this? It is still a persistent and annoying problem

[VLC_help]

Those messages are just generic de-sync messages so they don't really tell what the problem is.