I am using Icecast 2 Win32 to stream audio in conjunction with EdcastStandalone using the Ogg Vorbis and FLAC codecs due to these codecs providing better sound quality than mp3, FLAC being lossless.
When playing a stream encoded in Ogg Vorbis or FLAC using VLC Player the player stops playback and buffers the audio whenever the encoded bitrate is 0bps (as displayed in the encoder) indicating the presence of a deliberate silent interlude in the music or audio stream. Thus if there is a delay between tracks such as 2 to 4 seconds of silence between CD tracks or even longer interludes of silence between classical music tracks in symphonies and concertos which are an integral part of the compositions themselves, VLC player will stop playing while still connected to the stream waiting until it receives non-zero samples from the Icecast2 server.
As a result of this playback issue the periods of silence between tracks, and even during the middle of tracks such as classical music tracks are doubled and this is cumulative.
If there is 10 seconds of silence between tracks VLC player will stop playing the audio and the played back time display on the player will stop as well at the time when the silence or zero samples were encoded into the stream for the duration of the silence. VLC player will then remain in this state until it registers non-zero samples in the audio and then when it detects non-zero samples in the audio stream playback will resume from the point it paused. Thus if there is 10 seconds of silence VLC player will remain silent for 10 seconds frozen on say 3 minutes, 5 seconds of the track and then when the non-zero samples are picked up will start again from 3 minutes 5 seconds (the time in seconds in an Ogg Vorbis or FLAC stream or file from the start is encoded using frame numbers in the Ogg container format by design), the 10 seconds that originally expired when VLC player paused will then be played back until the time code reaches 3 minutes 15 seconds and then the non-zero samples of the music or audio will then be played 20 seconds after the first silence occurred. The duration of the silence or interlude is therefore doubled.
This issue does not occur when playing back streams encoded using mp3 since the bitrate never drops to 0bps. It only occurs when playing back Ogg Vorbis and FLAC streams and as time progresses with consecutive periods of silence the delay between the live performance and playback gets longer and longer since VLC player doubles the duration of the silence every time. This makes listening to classical music the way it was recorded practically impossible and makes listening to CD tracks frustrating as the stream gets more and more out of sync with the original source unless the player is manually stopped and started again.
Other players such as SM Player do not suffer from this issue. SM Player will buffer an Ogg Vorbis or FLAC stream for about 12 seconds and if it encounters audio samples at -infinity dB ie. zero samples it will continue playback normally with the original duration of the gaps between tracks being preserved. It does suffer from bugs playing back Ogg Vorbis or FLAC streams which cause it to repeat the last second of audio, if a track stops or silent interludes occur at -infinity dB, if the playback buffer has not been filled up properly.
These bugs have probably been there for years and it is surprising no one has noticed them before and figured out the cause.
Is it possible to fix this issue and get Ogg Vorbis and FLAC streams to play black properly?
All audio is routed digitally using:
player: VLC Player 3.0.20 Vetinari
server: Icecast2 Win32 2.3.2
encoder: Edcast 3.37.2011.1214
mixer: Voicemeeter Banana 2.0.6.8
source: Virtual DJ v2024 b8263
VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
Forum rules
Please post only Windows specific questions in this forum category. If you don't know where to post, please read the different forums' rules. Thanks.
Please post only Windows specific questions in this forum category. If you don't know where to post, please read the different forums' rules. Thanks.
-
Lotesdelere
- Cone Master
- Posts: 10005
- Joined: 08 Sep 2006 04:39
- Location: Europe
Re: VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
We need the full log, from the very beginning to the very end. Please open Tools -> Messages (set Verbosity to 2) before you start the playback, keep the messages window open during the whole process and then paste the full resulting log here or on Pastebin.com or on Justpaste.it if it's too long.
Re: VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
Here is the log file after I placed a track on Virtual DJ after Edcast had connected to the Icecast server using the Ogg Vorbis codec. I then pressed the play button to start the track on Virtual DJ. I then pressed play on VLC player to play back the stream. The stream started playing. After a few seconds I paused the track on Virtual DJ so that the audio level was at -infinity dB, and then restarted it about 10 seconds later.
When the track was paused VLC player started glitching and repeated the last second of the track about a couple of seconds after I'd paused the track and Virtual DJ was outputting silence at -infinity dB (ie. zeros). Then after I resumed playing the track VLC player took double the length of the pause for the track to be audible again, where if I had been streaming in mp3 format instead the audio from the track would have been heard less than about 5 seconds later. At no point during this procedure was the connection to Icecast dropped.
Log file part 1:
main debug: processing request item: stream, node: Playlist, skip: 0
main debug: resyncing on stream
main debug: stream is at 8
main debug: starting playback of new item
main debug: resyncing on stream
main debug: stream is at 8
main debug: creating new input thread
main debug: Creating an input for 'stream'
main debug: selected audio language[0] en
main debug: requesting art for new input thread
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path: I:\My Videos\Video Captures
main debug: `http://localhost:8089/stream' gives access `http' demux `any' path `localhost:8089/stream'
main debug: creating demux: access='http' demux='any' location='localhost:8089/stream' file='\\localhost:8089\stream'
main debug: looking for access_demux module matching "http": 15 candidates
main debug: looking for meta fetcher module matching "any": 1 candidates
main debug: no access_demux modules matched
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\fetcher
main debug: creating access: http://localhost:8089/stream
main debug: (path: \\localhost:8089\stream)
main debug: looking for access module matching "http": 27 candidates
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
qt debug: IM: Setting an input
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
http debug: resolving localhost ...
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
http debug: outgoing request: GET /stream HTTP/1.1 Host: localhost:8089 Accept: */* Accept-Language: en_GB User-Agent: VLC/3.0.20 LibVLC/3.0.20 Range: bytes=0-
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
main debug: no art finder modules matched
main debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\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: Trying Lua playlist script 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: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
main debug: no art finder modules matched
http debug: connection failed
http debug: outgoing request: GET /stream HTTP/1.1 Host: localhost:8089 Accept: */* Accept-Language: en_GB User-Agent: VLC/3.0.20 LibVLC/3.0.20 Range: bytes=0-
http debug: incoming response: HTTP/1.0 200 OK Content-Type: application/ogg ice-audio-info: ice-samplerate=44100;ice-bitrate=Quality 4;ice-channels=2 icy-br:Quality 4 icy-description:Laika kai Tsiftetelia tou Sokrati icy-genre:Greek icy-name:Greek Music icy-private:0 icy-pub:1 icy-url:http://www.oddsock.org Server: Icecast 2.3.2 Cache-Control: no-cache
http debug: connection failed
main debug: no access modules matched
main debug: redirecting to: icyx://localhost:8089/stream
main debug: creating access: icyx://localhost:8089/stream
main debug: (path: \\localhost:8089\stream)
main debug: looking for access module matching "icyx": 27 candidates
http debug: querying proxy for icyx://localhost:8089/stream
http debug: no proxy
http debug: http: server='localhost' port=8089 file='/stream'
main debug: net: connecting to localhost port 8089
main error: connection failed: Connection refused by peer
main debug: connection succeeded (socket = 2544)
http debug: sending request: GET /stream HTTP/1.0 Host: localhost:8089 User-Agent: VLC/3.0.20 LibVLC/3.0.20 Icy-MetaData: 1
When the track was paused VLC player started glitching and repeated the last second of the track about a couple of seconds after I'd paused the track and Virtual DJ was outputting silence at -infinity dB (ie. zeros). Then after I resumed playing the track VLC player took double the length of the pause for the track to be audible again, where if I had been streaming in mp3 format instead the audio from the track would have been heard less than about 5 seconds later. At no point during this procedure was the connection to Icecast dropped.
Log file part 1:
main debug: processing request item: stream, node: Playlist, skip: 0
main debug: resyncing on stream
main debug: stream is at 8
main debug: starting playback of new item
main debug: resyncing on stream
main debug: stream is at 8
main debug: creating new input thread
main debug: Creating an input for 'stream'
main debug: selected audio language[0] en
main debug: requesting art for new input thread
main debug: using timeshift granularity of 50 MiB
main debug: using timeshift path: I:\My Videos\Video Captures
main debug: `http://localhost:8089/stream' gives access `http' demux `any' path `localhost:8089/stream'
main debug: creating demux: access='http' demux='any' location='localhost:8089/stream' file='\\localhost:8089\stream'
main debug: looking for access_demux module matching "http": 15 candidates
main debug: looking for meta fetcher module matching "any": 1 candidates
main debug: no access_demux modules matched
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\fetcher
main debug: creating access: http://localhost:8089/stream
main debug: (path: \\localhost:8089\stream)
main debug: looking for access module matching "http": 27 candidates
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
qt debug: IM: Setting an input
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
http debug: resolving localhost ...
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
http debug: outgoing request: GET /stream HTTP/1.1 Host: localhost:8089 Accept: */* Accept-Language: en_GB User-Agent: VLC/3.0.20 LibVLC/3.0.20 Range: bytes=0-
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
main debug: no art finder modules matched
main debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\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: Trying Lua playlist script 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: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
main debug: no art finder modules matched
http debug: connection failed
http debug: outgoing request: GET /stream HTTP/1.1 Host: localhost:8089 Accept: */* Accept-Language: en_GB User-Agent: VLC/3.0.20 LibVLC/3.0.20 Range: bytes=0-
http debug: incoming response: HTTP/1.0 200 OK Content-Type: application/ogg ice-audio-info: ice-samplerate=44100;ice-bitrate=Quality 4;ice-channels=2 icy-br:Quality 4 icy-description:Laika kai Tsiftetelia tou Sokrati icy-genre:Greek icy-name:Greek Music icy-private:0 icy-pub:1 icy-url:http://www.oddsock.org Server: Icecast 2.3.2 Cache-Control: no-cache
http debug: connection failed
main debug: no access modules matched
main debug: redirecting to: icyx://localhost:8089/stream
main debug: creating access: icyx://localhost:8089/stream
main debug: (path: \\localhost:8089\stream)
main debug: looking for access module matching "icyx": 27 candidates
http debug: querying proxy for icyx://localhost:8089/stream
http debug: no proxy
http debug: http: server='localhost' port=8089 file='/stream'
main debug: net: connecting to localhost port 8089
main error: connection failed: Connection refused by peer
main debug: connection succeeded (socket = 2544)
http debug: sending request: GET /stream HTTP/1.0 Host: localhost:8089 User-Agent: VLC/3.0.20 LibVLC/3.0.20 Icy-MetaData: 1
Last edited by Argyros on 02 Aug 2024 23:55, edited 1 time in total.
Re: VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
Log file part 2:
http debug: HTTP answer code 200
http debug: Content-Type: application/ogg
http debug: Meta-Info: ice-audio-info: ice-samplerate=44100;ice-bitrate=Quality 4;ice-channels=2
http debug: Meta-Info: icy-br: Quality 4
http debug: Meta-Info: icy-description: Laika kai Tsiftetelia tou Sokrati
http debug: Icy-Genre: Greek
http debug: Icy-Name: Greek Music
http debug: Meta-Info: icy-private: 0
http debug: Meta-Info: icy-pub: 1
http debug: Meta-Info: icy-url: http://www.oddsock.org
http debug: Server: Icecast 2.3.2
http debug: auto re-connect enabled
main debug: using access module "http"
main debug: looking for stream_filter module matching "prefetch,cache_read": 24 candidates
prefetch debug: using 16777216 bytes buffer, 16777216 bytes read
main debug: using stream_filter module "prefetch"
main debug: looking for stream_filter module matching "any": 24 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\playlist
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\playlist
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\anevia_streams.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\anevia_xml.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\appletrailers.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\bbc_co_uk.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\cue.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\dailymotion.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\jamendo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\koreus.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\liveleak.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\newgrounds.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\rockbox_fm_presets.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\soundcloud.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\twitch.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\vimeo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\vocaroo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\youtube.luac
main debug: no stream_filter modules matched
main debug: looking for stream_directory module matching "any": 1 candidates
main debug: no stream_directory modules matched
main debug: attachment of directory-extractor failed for icyx://localhost:8089/stream
main debug: looking for stream_filter module matching "record": 24 candidates
main debug: using stream_filter module "record"
main debug: creating demux: access='http' demux='any' location='localhost:8089/stream' file='\\localhost:8089\stream'
main debug: looking for demux module matching "any": 55 candidates
ogg debug: File length is 0 bytes
ogg debug: found vorbis header
ogg debug: beginning of a group of logical streams
main debug: using demux module "ogg"
main debug: looking for meta reader module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader modules matched
main debug: `http://localhost:8089/stream' successfully opened
main debug: selecting program id=0
main debug: looking for audio decoder module matching "any": 23 candidates
main debug: using audio decoder module "vorbis"
ogg warning: Consider increasing access caching variable from 1000 to >62685
main debug: Buffering 0%
main debug: Buffering 25%
main debug: Buffering 52%
vorbis debug: channels:2 samplerate:44100 bitrate:128003d
main debug: Buffering 81%
main debug: Stream buffering done (1053 ms in 1 ms)
main debug: reusing audio output
main debug: looking for aout stream module matching "any": 2 candidates
wasapi debug: modified format
wasapi debug: buffer size : 96000 frames
wasapi debug: maximum latency: 000 ns
wasapi debug: default period : 10000000 ns
wasapi debug: minimum period : 3000000 ns
main debug: using aout stream module "wasapi"
main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio volume module matching "any": 2 candidates
main debug: using audio volume module "float_mixer"
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module matching "scaletempo": 16 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
main debug: using audio filter module "scaletempo"
main debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: looking for audio resampler module matching "any": 3 candidates
main debug: using audio resampler module "samplerate"
main debug: Decoder wait done in 29 ms
main debug: inserting 463 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (71899): up-sampling
main warning: timing screwed (drift: 165442 us): stopping resampling
main warning: playback way too late (188316): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 409 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (60073): up-sampling
mmdevice debug: simple volume changed: 0.205379, muting disabled
mmdevice debug: simple volume changed: 0.205379, muting disabled
mmdevice debug: simple volume changed: 0.274625, muting disabled
mmdevice debug: simple volume changed: 0.274625, muting disabled
mmdevice debug: simple volume changed: 0.328509, muting disabled
mmdevice debug: simple volume changed: 0.328509, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.493039, muting disabled
mmdevice debug: simple volume changed: 0.493039, muting disabled
mmdevice debug: simple volume changed: 0.614125, muting disabled
mmdevice debug: simple volume changed: 0.614125, muting disabled
mmdevice debug: simple volume changed: 0.531441, muting disabled
mmdevice debug: simple volume changed: 0.531441, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
http debug: HTTP answer code 200
http debug: Content-Type: application/ogg
http debug: Meta-Info: ice-audio-info: ice-samplerate=44100;ice-bitrate=Quality 4;ice-channels=2
http debug: Meta-Info: icy-br: Quality 4
http debug: Meta-Info: icy-description: Laika kai Tsiftetelia tou Sokrati
http debug: Icy-Genre: Greek
http debug: Icy-Name: Greek Music
http debug: Meta-Info: icy-private: 0
http debug: Meta-Info: icy-pub: 1
http debug: Meta-Info: icy-url: http://www.oddsock.org
http debug: Server: Icecast 2.3.2
http debug: auto re-connect enabled
main debug: using access module "http"
main debug: looking for stream_filter module matching "prefetch,cache_read": 24 candidates
prefetch debug: using 16777216 bytes buffer, 16777216 bytes read
main debug: using stream_filter module "prefetch"
main debug: looking for stream_filter module matching "any": 24 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\playlist
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\playlist
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\anevia_streams.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\anevia_xml.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\appletrailers.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\bbc_co_uk.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\cue.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\dailymotion.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\jamendo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\koreus.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\liveleak.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\newgrounds.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\rockbox_fm_presets.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\soundcloud.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\twitch.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\vimeo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\vocaroo.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\playlist\youtube.luac
main debug: no stream_filter modules matched
main debug: looking for stream_directory module matching "any": 1 candidates
main debug: no stream_directory modules matched
main debug: attachment of directory-extractor failed for icyx://localhost:8089/stream
main debug: looking for stream_filter module matching "record": 24 candidates
main debug: using stream_filter module "record"
main debug: creating demux: access='http' demux='any' location='localhost:8089/stream' file='\\localhost:8089\stream'
main debug: looking for demux module matching "any": 55 candidates
ogg debug: File length is 0 bytes
ogg debug: found vorbis header
ogg debug: beginning of a group of logical streams
main debug: using demux module "ogg"
main debug: looking for meta reader module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\Argyros Argyrou\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader modules matched
main debug: `http://localhost:8089/stream' successfully opened
main debug: selecting program id=0
main debug: looking for audio decoder module matching "any": 23 candidates
main debug: using audio decoder module "vorbis"
ogg warning: Consider increasing access caching variable from 1000 to >62685
main debug: Buffering 0%
main debug: Buffering 25%
main debug: Buffering 52%
vorbis debug: channels:2 samplerate:44100 bitrate:128003d
main debug: Buffering 81%
main debug: Stream buffering done (1053 ms in 1 ms)
main debug: reusing audio output
main debug: looking for aout stream module matching "any": 2 candidates
wasapi debug: modified format
wasapi debug: buffer size : 96000 frames
wasapi debug: maximum latency: 000 ns
wasapi debug: default period : 10000000 ns
wasapi debug: minimum period : 3000000 ns
main debug: using aout stream module "wasapi"
main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio volume module matching "any": 2 candidates
main debug: using audio volume module "float_mixer"
main debug: input 'f32l' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module matching "scaletempo": 16 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
main debug: using audio filter module "scaletempo"
main debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: conversion: 'f32l'->'f32l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: looking for audio resampler module matching "any": 3 candidates
main debug: using audio resampler module "samplerate"
main debug: Decoder wait done in 29 ms
main debug: inserting 463 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (71899): up-sampling
main warning: timing screwed (drift: 165442 us): stopping resampling
main warning: playback way too late (188316): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 409 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (60073): up-sampling
mmdevice debug: simple volume changed: 0.205379, muting disabled
mmdevice debug: simple volume changed: 0.205379, muting disabled
mmdevice debug: simple volume changed: 0.274625, muting disabled
mmdevice debug: simple volume changed: 0.274625, muting disabled
mmdevice debug: simple volume changed: 0.328509, muting disabled
mmdevice debug: simple volume changed: 0.328509, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.493039, muting disabled
mmdevice debug: simple volume changed: 0.493039, muting disabled
mmdevice debug: simple volume changed: 0.614125, muting disabled
mmdevice debug: simple volume changed: 0.614125, muting disabled
mmdevice debug: simple volume changed: 0.531441, muting disabled
mmdevice debug: simple volume changed: 0.531441, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
mmdevice debug: simple volume changed: 0.421875, muting disabled
Re: VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
Log file part 3:
main debug: resampling stopped (drift: -24144 us)
main warning: buffer too late (-2114296 us): dropped
main warning: buffer too late (-2091076 us): dropped
main warning: buffer too late (-2068856 us): dropped
main warning: buffer too late (-2045636 us): dropped
main warning: buffer too late (-2022416 us): dropped
main warning: buffer too late (-2009355 us): dropped
main warning: buffer too late (-2006453 us): dropped
main warning: buffer too late (-2003550 us): dropped
main warning: buffer too late (-2000648 us): dropped
main warning: buffer too late (-1997745 us): dropped
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1000 ms)
main warning: buffer too late (-1994843 us): dropped
main debug: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: Buffering 32%
main debug: Buffering 61%
main debug: Stream buffering done (1123 ms in 5899 ms)
main debug: Decoder wait done in 0 ms
main warning: buffer too late (-496486 us): dropped
mmdevice debug: state changed: 0
wasapi debug: reset
main warning: buffer too late (-289507 us): dropped
main warning: buffer too late (-286604 us): dropped
main warning: buffer too late (-283702 us): dropped
main warning: buffer too late (-280799 us): dropped
main warning: buffer too late (-267738 us): dropped
main warning: buffer too late (-244518 us): dropped
main warning: buffer too late (-221298 us): dropped
main warning: buffer too late (-198078 us): dropped
main warning: buffer too late (-174858 us): dropped
main warning: buffer too late (-151638 us): dropped
main warning: buffer too late (-128418 us): dropped
main warning: buffer too late (-105198 us): dropped
main warning: buffer too late (-92137 us): dropped
main warning: buffer too late (-89235 us): dropped
main warning: buffer too late (-86332 us): dropped
main warning: buffer too late (-83430 us): dropped
main warning: buffer too late (-80527 us): dropped
main warning: buffer too late (-77625 us): dropped
main warning: buffer too late (-74722 us): dropped
main warning: buffer too late (-62661 us): dropped
main debug: playback too late (39440): flushing buffers
wasapi debug: reset
mmdevice debug: state changed: 1
main warning: buffer too late (-10312541 us): dropped
main warning: buffer too late (-10289321 us): dropped
main warning: buffer too late (-10266101 us): dropped
main warning: buffer too late (-10242881 us): dropped
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 5278 ms)
main debug: ES_OUT_RESET_PCR called
main warning: buffer too late (-10219661 us): dropped
main debug: Buffering 0%
mmdevice debug: state changed: 0
main debug: Buffering 9%
main debug: Buffering 19%
wasapi debug: reset
main debug: Buffering 29%
main debug: Buffering 38%
main debug: Buffering 48%
main debug: Buffering 58%
main debug: Buffering 67%
main debug: Buffering 77%
main debug: Buffering 87%
main debug: Buffering 96%
main debug: Stream buffering done (5619 ms in 2 ms)
main debug: Decoder wait done in 0 ms
main warning: buffer too late (-500840 us): dropped
main warning: buffer too late (-477620 us): dropped
main warning: buffer too late (-454400 us): dropped
main warning: buffer too late (-431180 us): dropped
main warning: buffer too late (-407960 us): dropped
main warning: buffer too late (-384740 us): dropped
main warning: buffer too late (-361520 us): dropped
main warning: buffer too late (-338300 us): dropped
main warning: buffer too late (-315080 us): dropped
main warning: buffer too late (-291860 us): dropped
main warning: buffer too late (-268640 us): dropped
main warning: buffer too late (-245420 us): dropped
main warning: buffer too late (-222200 us): dropped
main warning: buffer too late (-198980 us): dropped
main warning: buffer too late (-175760 us): dropped
main warning: buffer too late (-152540 us): dropped
main warning: buffer too late (-129320 us): dropped
main warning: buffer too late (-107100 us): dropped
main warning: buffer too late (-83880 us): dropped
main warning: buffer too late (-60660 us): dropped
main debug: playback too late (37440): flushing buffers
wasapi debug: reset
mmdevice debug: state changed: 1
main warning: playback too late (61387): up-sampling
main debug: resampling stopped (drift: 8869 us)
main debug: incoming request - stopping current input
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: killing decoder fourcc `vorb'
main debug: removing module "vorbis"
wasapi debug: reset
main debug: removing module "samplerate"
main debug: removing module "scaletempo"
main debug: removing module "float_mixer"
main debug: keeping audio output
main debug: removing module "ogg"
main debug: Program doesn't contain anymore ES
main debug: removing module "record"
main debug: removing module "prefetch"
prefetch debug: end of stream
main debug: removing module "http"
main debug: dead input
main debug: nothing to play
qt debug: IM: Deleting the input
main debug: resampling stopped (drift: -24144 us)
main warning: buffer too late (-2114296 us): dropped
main warning: buffer too late (-2091076 us): dropped
main warning: buffer too late (-2068856 us): dropped
main warning: buffer too late (-2045636 us): dropped
main warning: buffer too late (-2022416 us): dropped
main warning: buffer too late (-2009355 us): dropped
main warning: buffer too late (-2006453 us): dropped
main warning: buffer too late (-2003550 us): dropped
main warning: buffer too late (-2000648 us): dropped
main warning: buffer too late (-1997745 us): dropped
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1000 ms)
main warning: buffer too late (-1994843 us): dropped
main debug: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: Buffering 32%
main debug: Buffering 61%
main debug: Stream buffering done (1123 ms in 5899 ms)
main debug: Decoder wait done in 0 ms
main warning: buffer too late (-496486 us): dropped
mmdevice debug: state changed: 0
wasapi debug: reset
main warning: buffer too late (-289507 us): dropped
main warning: buffer too late (-286604 us): dropped
main warning: buffer too late (-283702 us): dropped
main warning: buffer too late (-280799 us): dropped
main warning: buffer too late (-267738 us): dropped
main warning: buffer too late (-244518 us): dropped
main warning: buffer too late (-221298 us): dropped
main warning: buffer too late (-198078 us): dropped
main warning: buffer too late (-174858 us): dropped
main warning: buffer too late (-151638 us): dropped
main warning: buffer too late (-128418 us): dropped
main warning: buffer too late (-105198 us): dropped
main warning: buffer too late (-92137 us): dropped
main warning: buffer too late (-89235 us): dropped
main warning: buffer too late (-86332 us): dropped
main warning: buffer too late (-83430 us): dropped
main warning: buffer too late (-80527 us): dropped
main warning: buffer too late (-77625 us): dropped
main warning: buffer too late (-74722 us): dropped
main warning: buffer too late (-62661 us): dropped
main debug: playback too late (39440): flushing buffers
wasapi debug: reset
mmdevice debug: state changed: 1
main warning: buffer too late (-10312541 us): dropped
main warning: buffer too late (-10289321 us): dropped
main warning: buffer too late (-10266101 us): dropped
main warning: buffer too late (-10242881 us): dropped
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 5278 ms)
main debug: ES_OUT_RESET_PCR called
main warning: buffer too late (-10219661 us): dropped
main debug: Buffering 0%
mmdevice debug: state changed: 0
main debug: Buffering 9%
main debug: Buffering 19%
wasapi debug: reset
main debug: Buffering 29%
main debug: Buffering 38%
main debug: Buffering 48%
main debug: Buffering 58%
main debug: Buffering 67%
main debug: Buffering 77%
main debug: Buffering 87%
main debug: Buffering 96%
main debug: Stream buffering done (5619 ms in 2 ms)
main debug: Decoder wait done in 0 ms
main warning: buffer too late (-500840 us): dropped
main warning: buffer too late (-477620 us): dropped
main warning: buffer too late (-454400 us): dropped
main warning: buffer too late (-431180 us): dropped
main warning: buffer too late (-407960 us): dropped
main warning: buffer too late (-384740 us): dropped
main warning: buffer too late (-361520 us): dropped
main warning: buffer too late (-338300 us): dropped
main warning: buffer too late (-315080 us): dropped
main warning: buffer too late (-291860 us): dropped
main warning: buffer too late (-268640 us): dropped
main warning: buffer too late (-245420 us): dropped
main warning: buffer too late (-222200 us): dropped
main warning: buffer too late (-198980 us): dropped
main warning: buffer too late (-175760 us): dropped
main warning: buffer too late (-152540 us): dropped
main warning: buffer too late (-129320 us): dropped
main warning: buffer too late (-107100 us): dropped
main warning: buffer too late (-83880 us): dropped
main warning: buffer too late (-60660 us): dropped
main debug: playback too late (37440): flushing buffers
wasapi debug: reset
mmdevice debug: state changed: 1
main warning: playback too late (61387): up-sampling
main debug: resampling stopped (drift: 8869 us)
main debug: incoming request - stopping current input
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: killing decoder fourcc `vorb'
main debug: removing module "vorbis"
wasapi debug: reset
main debug: removing module "samplerate"
main debug: removing module "scaletempo"
main debug: removing module "float_mixer"
main debug: keeping audio output
main debug: removing module "ogg"
main debug: Program doesn't contain anymore ES
main debug: removing module "record"
main debug: removing module "prefetch"
prefetch debug: end of stream
main debug: removing module "http"
main debug: dead input
main debug: nothing to play
qt debug: IM: Deleting the input
Re: VLC Player not playing back silent interludes properly in streams using Ogg Vorbis and FLAC if bitrate is 0bps
I have found a temporary external workaround which stops this problem occurring.
1) Create a 30 second sample of white noise at amplitude 0.000015 in Audacity and save it as FLAC. Audacity will quantize it at a level of exactly -90dB even though it's at almost -96dB.
2) Place the FLAC encoded sample on one of the Sampler pads on VirtualDJ and edit the pad so that the sample plays back as a loop.
3) Click on the pad to activate the loop and keep it active while you are playing music. This will cause the Ogg Vorbis encoder in Edcast to force the bitrate to 75Kbps which stops VLC Player from thinking that the stream has stopped on encountering zero samples which causes it to stutter and buffer silence until the track has started playing again above -infinity dB or the interlude on a classical music composition has elapsed and the orchestra starts playing the next movement.
Using the FLAC encoder on Edcast will not cause the problem if the encoded bitrate is higher than 0 Kbps.
There is clearly an issue in the way Ogg encapsulated streams are processed by VLC Player and other media players including the ones built into Chromium/Edge, Mozilla Firefox, and even SM Player which causes all of them to stutter, repeat the last frame or more of audio, and/or stop playing and buffer silence whenever they encounter 0 samples (-infinity dB) in an audio stream.
If this is intended behavior then it is badly thought out. Why allow the player to stutter when a track goes silent?
If the developers of any of the above mentioned players or others are reading this can you please remove this behavior from your player. When zero samples are encountered in an Ogg stream the player should play the intended silence as intended. It should not stop, not stutter, and not buffer the silence, and then playback the buffered silence for its entire duration the moment the sound resumes at above -infinity dB doubling the duration of the silent interval in the process, which was never intended to be part of the original performance/recording, before playing back the resumed audio from rest of the streamed track or performance.
1) Create a 30 second sample of white noise at amplitude 0.000015 in Audacity and save it as FLAC. Audacity will quantize it at a level of exactly -90dB even though it's at almost -96dB.
2) Place the FLAC encoded sample on one of the Sampler pads on VirtualDJ and edit the pad so that the sample plays back as a loop.
3) Click on the pad to activate the loop and keep it active while you are playing music. This will cause the Ogg Vorbis encoder in Edcast to force the bitrate to 75Kbps which stops VLC Player from thinking that the stream has stopped on encountering zero samples which causes it to stutter and buffer silence until the track has started playing again above -infinity dB or the interlude on a classical music composition has elapsed and the orchestra starts playing the next movement.
Using the FLAC encoder on Edcast will not cause the problem if the encoded bitrate is higher than 0 Kbps.
There is clearly an issue in the way Ogg encapsulated streams are processed by VLC Player and other media players including the ones built into Chromium/Edge, Mozilla Firefox, and even SM Player which causes all of them to stutter, repeat the last frame or more of audio, and/or stop playing and buffer silence whenever they encounter 0 samples (-infinity dB) in an audio stream.
If this is intended behavior then it is badly thought out. Why allow the player to stutter when a track goes silent?
If the developers of any of the above mentioned players or others are reading this can you please remove this behavior from your player. When zero samples are encountered in an Ogg stream the player should play the intended silence as intended. It should not stop, not stutter, and not buffer the silence, and then playback the buffered silence for its entire duration the moment the sound resumes at above -infinity dB doubling the duration of the silent interval in the process, which was never intended to be part of the original performance/recording, before playing back the resumed audio from rest of the streamed track or performance.
Return to “VLC media player for Windows Troubleshooting”
Who is online
Users browsing this forum: No registered users and 71 guests