The VideoLAN Forums

Posted: **05 Mar 2015 10:58**

When I start to play a WMA track or move to the next track in playlist, it plays first ~ 500 ms, then stops for another ~ 500 ms and continues to play the rest of track. Older 2.1.5 version did not have the issue.

Code: Select all

core debug: processing request item: 1234567890, node: Media Library, skip: 0
core debug: resyncing on 1234567890
core debug: 1234567890 is at 24
core debug: starting playback of the new playlist item
core debug: resyncing on 1234567890
core debug: 1234567890 is at 24
core debug: creating new input thread
core debug: Creating an input for '1234567890'
core debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\User\LOCALS~1\Temp'
core debug: requesting art for 1234567890
core debug: `file:///C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma' gives access `file' demux `' path `/C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma'
core debug: specified demux `any'
core debug: creating demux: access='file' demux='any' location='/C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma' file='C:\Documents and Settings\User\My Documents\My Music\2014\[-ABCD-] - 1234567890 {111111111}\02 1234567890.wma'
core debug: looking for access_demux module matching "file": 12 candidates
core debug: no access_demux modules matched
core debug: creating access 'file' location='/C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma', path='C:\Documents and Settings\User\My Documents\My Music\2014\[-ABCD-] - 1234567890 {111111111}\02 1234567890.wma'
core debug: looking for access module matching "file": 21 candidates
filesystem debug: opening file `C:\Documents and Settings\User\My Documents\My Music\2014\[-ABCD-] - 1234567890 {111111111}\02 1234567890.wma'
core debug: using access module "filesystem"
core debug: Using stream method for AStream*
core debug: starting pre-buffering
core debug: received first data after 0 ms
core debug: pre-buffering done 1024 bytes in 0s - 1000000 KiB/s
core debug: looking for stream_filter module matching "any": 6 candidates
core debug: no stream_filter modules matched
core debug: looking for stream_filter module matching "record": 6 candidates
core debug: using stream_filter module "record"
core debug: creating demux: access='file' demux='any' location='/C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma' file='C:\Documents and Settings\User\My Documents\My Music\2014\[-ABCD-] - 1234567890 {111111111}\02 1234567890.wma'
core debug: looking for demux module matching "asf": 65 candidates
asf warning: unknown asf object (not loaded): 0xd6e229df-0x35da-0x11d1-0x903400a0c90349be
asf warning: unknown asf object (not loaded): 0xd6e229d3-0x35da-0x11d1-0x903400a0c90349be
asf warning: unknown top-level object found: 0xd6e229d3-0x35da-0x11d1-0x903400a0c90349be
asf debug: + 'Root'
asf debug: | + 'Header'
asf debug: | | + 'File Properties'
asf debug: | | + 'Header Extension'
asf debug: | | | + 'Object Compatibility'
asf debug: | | | + 'Metadata'
asf debug: | | | + 'Padding'
asf debug: | | | + 'Extended Stream Properties'
asf debug: | | | + 'Unknown'
asf debug: | | + 'Extended content description'
asf debug: | | + 'Codec List'
asf debug: | | + 'Stream Properties'
asf debug: | | + 'Stream Bitrate Properties'
asf debug: | | + 'Content Description'
asf debug: | + 'Data'
asf debug: | + 'Index'
asf debug: | + 'Simple Index'
asf debug: found 1 streams
asf debug: added new audio stream(codec:0x161,ID:1)
core debug: selecting program id=0
core debug: using demux module "asf"
core debug: looking for a subtitle file in C:\Documents and Settings\User\My Documents\My Music\2014\[-ABCD-] - 1234567890 {111111111}\
core debug: looking for decoder module matching "any": 43 candidates
avcodec debug: CPU flags: 0x4100100b
avcodec debug: avcodec codec (Windows Media Audio 2) started
core debug: using decoder module "avcodec"
core debug: looking for meta reader module matching "any": 2 candidates
core debug: using meta reader module "taglib"
core debug: removing module "taglib"
core debug: `file:///C:/Documents%20and%20Settings/User/My%20Documents/My%20Music/2014/%5B-ABCD-%5D%20-%201234567890%20%111111111%7D/02%201234567890.wma' successfully opened
avcodec warning: Physical channel configuration not set : guessing
core debug: reusing audio output
directsound debug: Opening DirectSound Audio Output
core debug: Buffering 0%
core debug: Stream buffering done (482 ms in 0 ms)
core debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Documents and Settings\User\Application Data\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
core debug: no meta fetcher modules matched
core debug: ABCD - 1234567890 has already been searched
core debug: art not found for 1234567890
directsound debug: Windows speaker config: Stereo and stream has 2 channels, using 2 channels
core debug: output 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
core debug: looking for audio volume module matching "any": 2 candidates
core debug: using audio volume module "float_mixer"
core debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
core debug: looking for audio filter module matching "scaletempo": 14 candidates
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
core debug: using audio filter module "scaletempo"
core debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
core debug: conversion pipeline complete
core debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
core debug: conversion pipeline complete
core debug: looking for audio resampler module matching "any": 3 candidates
core debug: using audio resampler module "samplerate"
core debug: End of audio preroll
core debug: Decoder wait done in 120 ms
core warning: buffer too late (-129000 us): dropped
core warning: playback way too early (-207626): playing silence
core debug: inserting 9156 zeroes
qt4 debug: IM: Setting an input
core debug: incoming request - stopping current input
core debug: control: stopping input
core debug: incoming request - stopping current input
core debug: finished input
core debug: removing module "avcodec"
avcodec debug: ffmpeg codec (Windows Media Audio 2) stopped
core debug: killing decoder fourcc `WMA2', 0 PES in FIFO
core debug: removing module "samplerate"
core debug: removing module "scaletempo"
directsound debug: closing audio device
core debug: removing module "float_mixer"
core debug: keeping audio output
core debug: removing module "asf"
core debug: Program doesn't contain anymore ES
core debug: removing module "record"
core debug: removing module "filesystem"
core debug: incoming request - stopping current input
core debug: dead input
qt4 debug: IM: Deleting the input

Posted: **06 Mar 2015 13:54**

I can't reproduce the issue, no problem here with v2.2.0 so far.

Please open Tools -> Messages (set Verbosity to 2) before you start the playback, and then paste the full resulting log here or on Pastebin.com if it's too long.

Also upload a short sample of a problematic file to either http://streams.videolan.org/upload/ or to Zippyshare.com (200 MB max) or to EmbedUpload.com, the latter will upload the file for you to several other hosts (use the default ones) and then post the link to the file here.

Posted: **09 Mar 2015 13:49**

It is full log after pressing Play button.

Posted: **09 Mar 2015 14:03**

We still need a short sample file.

Posted: **20 Mar 2015 21:09**

I made short 30 sec sample. It is sine wave created by Audacity and converted to WMA2 by Microsoft Expression Encoder 4. Notice the dropout at 0:01 position. The dropout also occurs in one second after seeking to a different position. There is no issue playing the sample file in VLC 2.1.5.

Sample file (518 KB): http://members.upc.cz/petr/temp/VLC220_ ... sample.wma

VLC 2.2.0 log:

Code: Select all

core debug: processing request item: null, node: Playlist, skip: 0
core debug: rebuilding array of current - root Playlist
core debug: rebuild done - 1 items, index -1
core debug: starting playback of the new playlist item
core debug: resyncing on VLC220_WMA2_dropout_bug_sample.wma
core debug: VLC220_WMA2_dropout_bug_sample.wma is at 0
core debug: creating new input thread
core debug: Creating an input for 'VLC220_WMA2_dropout_bug_sample.wma'
core debug: requesting art for VLC220_WMA2_dropout_bug_sample.wma
qt4 debug: IM: Setting an input
core debug: using timeshift granularity of 50 MiB, in path 'C:\DOCUME~1\Petr\LOCALS~1\Temp'
core debug: `file:///C:/Pack/VLC220_WMA2_dropout_bug_sample.wma' gives access `file' demux `' path `/C:/Pack/VLC220_WMA2_dropout_bug_sample.wma'
core debug: specified demux `any'
core debug: creating demux: access='file' demux='any' location='/C:/Pack/VLC220_WMA2_dropout_bug_sample.wma' file='C:\Pack\VLC220_WMA2_dropout_bug_sample.wma'
core debug: looking for access_demux module matching "file": 12 candidates
core debug: no access_demux modules matched
core debug: creating access 'file' location='/C:/Pack/VLC220_WMA2_dropout_bug_sample.wma', path='C:\Pack\VLC220_WMA2_dropout_bug_sample.wma'
core debug: looking for access module matching "file": 21 candidates
filesystem debug: opening file `C:\Pack\VLC220_WMA2_dropout_bug_sample.wma'
core debug: using access module "filesystem"
core debug: Using stream method for AStream*
core debug: starting pre-buffering
core debug: received first data after 3 ms
core debug: pre-buffering done 1024 bytes in 0s - 333 KiB/s
core debug: looking for stream_filter module matching "any": 6 candidates
core debug: no stream_filter modules matched
core debug: looking for stream_filter module matching "record": 6 candidates
core debug: using stream_filter module "record"
core debug: creating demux: access='file' demux='any' location='/C:/Pack/VLC220_WMA2_dropout_bug_sample.wma' file='C:\Pack\VLC220_WMA2_dropout_bug_sample.wma'
core debug: looking for demux module matching "asf": 65 candidates
asf warning: unknown asf object (not loaded): 0xd6e229df-0x35da-0x11d1-0x903400a0c90349be
asf warning: unknown asf object (not loaded): 0xd6e229d3-0x35da-0x11d1-0x903400a0c90349be
asf warning: unknown top-level object found: 0xd6e229d3-0x35da-0x11d1-0x903400a0c90349be
asf debug: + 'Root'
asf debug: | + 'Header'
asf debug: | | + 'File Properties'
asf debug: | | + 'Header Extension'
asf debug: | | | + 'Language List'
asf debug: | | | + 'Object Compatibility'
asf debug: | | | + 'Metadata'
asf debug: | | | + 'Padding'
asf debug: | | | + 'Extended Stream Properties'
asf debug: | | | + 'Unknown'
asf debug: | | + 'Extended content description'
asf debug: | | + 'Codec List'
asf debug: | | + 'Stream Properties'
asf debug: | | + 'Stream Bitrate Properties'
asf debug: | + 'Data'
asf debug: | + 'Index'
asf debug: | + 'Simple Index'
asf debug: found 1 streams
asf debug: added new audio stream(codec:0x161,ID:1)
core debug: selecting program id=0
core debug: using demux module "asf"
core debug: looking for a subtitle file in C:\Pack\
core debug: looking for decoder module matching "any": 43 candidates
avcodec debug: CPU flags: 0x4100107f
avcodec debug: avcodec codec (Windows Media Audio 2) started
core debug: using decoder module "avcodec"
core debug: looking for meta reader module matching "any": 2 candidates
core debug: using meta reader module "taglib"
core debug: removing module "taglib"
core debug: `file:///C:/Pack/VLC220_WMA2_dropout_bug_sample.wma' successfully opened
avcodec warning: Physical channel configuration not set : guessing
core debug: reusing audio output
directsound debug: Opening DirectSound Audio Output
core debug: Buffering 0%
core debug: Stream buffering done (696 ms in 0 ms)
core debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Documents and Settings\Petr\Application Data\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac
core debug: no meta fetcher modules matched
core debug: searching art for VLC220_WMA2_dropout_bug_sample.wma
core debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Documents and Settings\Petr\Application Data\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
core debug: no art finder modules matched
core debug: art not found for VLC220_WMA2_dropout_bug_sample.wma
directsound debug: Windows speaker config: Stereo and stream has 2 channels, using 2 channels
core debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
core debug: looking for audio volume module matching "any": 2 candidates
core debug: using audio volume module "integer_mixer"
core debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
core debug: conversion: 'f32l'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
core debug: looking for audio converter module matching "any": 12 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
core debug: using audio converter module "audio_format"
core debug: conversion pipeline complete
core debug: looking for audio resampler module matching "any": 3 candidates
core debug: using audio resampler module "ugly_resampler"
core debug: End of audio preroll
core debug: Decoder wait done in 166 ms
core warning: buffer too late (-380000 us): dropped
core warning: playback way too early (-260876): playing silence
core debug: inserting 11504 zeroes

Posted: **22 Mar 2015 22:40**

You should create a bugreport, mentioning it's a regression.

Posted: **25 Mar 2015 12:58**

Done https://trac.videolan.org/vlc/ticket/14250

Posted: **25 Mar 2015 23:34**

The VideoLAN Forums

2.2.0 playing WMA tracks ~ 500 ms silence gap at start

2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start

Re: 2.2.0 playing WMA tracks ~ 500 ms silence gap at start