Dropping Frames with 2.1.0 if Sharpen Turned On

[guykuo]

Recent VLC 2.1.0 release causes frame drops during use of video sharpening of 1080P playback.

Previously, VLC 2.0.8 was able to apply sharpening without dropping frames. Now on the same hardware (2.7 GHz Quad Core i7 MBP Retina) frames are frequently dropped when sharpen is applied. Perhaps 1/5 of frames are dropped and motion becomes jerky and sometimes even pauses as frames are dropped. The 2.10 release renders the sharpen filter unusable.

Reverting back to 2.0.8 once more allows same hardware and OSX version (10.8.5) allows sharpening once more without dropping frames.
The sharpen filter is highly desirable when VLC plays back on a large screen. It has to be applied judiciously, but renders beautifully on a 126 inch projection. Without the sharpening, HD looks blurry. Might not be an issue on a small screen, but on a big screen it is practically a must.

I DID reset all preference each time I changed VLC versions. That prevents some startup crashes, but has no effect on frame dropping in 2.10 when sharpen is applied.

[Jean-Baptiste Kempf]

Can you check the logs?

[guykuo]

Partial log with sharpen turned on under 2.1.0 during 1080p playback. Rest of log not posted because it simply adds lots more drop frame messages....

main debug: VLC media player - 2.1.0 Rincewind
main debug: Copyright © 1996-2013 the VideoLAN team
main debug: revision 2.1.0-0-gedd8835
main debug: configured with ../extras/package/macosx/../../../configure '--prefix=/Users/fpk/Desktop/vlc-2.1-final/release/vlc_install_dir' '--enable-macosx' '--enable-merge-ffmpeg' '--enable-growl' '--enable-faad' '--enable-flac' '--enable-theora' '--enable-shout' '--enable-ncurses' '--enable-twolame' '--enable-realrtsp' '--enable-libass' '--enable-macosx-audio' '--enable-macosx-eyetv' '--enable-macosx-qtkit' '--enable-macosx-avfoundation' '--enable-macosx-vout' '--disable-skins2' '--disable-xcb' '--disable-caca' '--disable-sdl' '--disable-samplerate' '--disable-macosx-dialog-provider' '--build=x86_64-apple-darwin10' '--with-macosx-sdk=/Applications/Xcode.app/Contents/Developer/Platforms/MacOSX.platform/Developer/SDKs/MacOSX10.7.sdk' '--with-macosx-version-min=10.6' 'build_alias=x86_64-apple-darwin10' 'CC=xcrun clang' 'CFLAGS=' 'LDFLAGS=' 'CXX=xcrun clang++' 'OBJC=xcrun clang'
main warning: picture is too late to be displayed (missing 47 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 28 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 6 ms)
main warning: picture is too late to be displayed (missing 36 ms)
main debug: picture might be displayed late (missing 11 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 10 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 35 ms)
main debug: picture might be displayed late (missing 5 ms)
main debug: picture might be displayed late (missing 16 ms)
main warning: picture is too late to be displayed (missing 46 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main debug: picture might be displayed late (missing 6 ms)
main warning: picture is too late to be displayed (missing 36 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 0 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 4 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 52 ms)
main debug: picture might be displayed late (missing 11 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 0 ms)

[guykuo]

Under 2.0.8 one sees the startup messages, but no frame drops messages, even after allowing several minutes playback and refreshing the log. You just see the sharpen filter applied message. No drops.

mkv debug: Virtual chapter Chapter 07 from 2406779375 to 2776023250 -
mkv debug: Virtual chapter Chapter 09 from 3142180708 to 3719716000 -
mkv debug: Virtual chapter Chapter 10 from 3719716000 to 4079408666 -
mkv debug: Virtual chapter Chapter 11 from 4079408666 to 4585831250 -
mkv debug: Virtual chapter Chapter 12 from 4585831250 to 4928173250 -
mkv debug: Virtual chapter Chapter 13 from 4928173250 to 5131751625 -
mkv debug: Virtual chapter Chapter 14 from 5131751625 to 5422542125 -
mkv debug: Virtual chapter Chapter 15 from 5422542125 to 5790367916 -
mkv debug: Virtual chapter Chapter 16 from 5790367916 to 6029106416 -
mkv debug: Virtual chapter Chapter 17 from 6029106416 to 6387422708 -
mkv debug: Virtual chapter Chapter 18 from 6387422708 to 6785320208 -
mkv debug: Virtual chapter Chapter 19 from 6785320208 to 7103846750 -
mkv debug: Virtual chapter Chapter 20 from 7103846750 to 7482308166 -
mkv debug: Virtual chapter from 0 to -1 -
mkv debug: found 3 es
main debug: selecting program id=0
mkv debug: Starting the UI Hook
main debug: using demux module "mkv"
main debug: TIMER module_need() : 58.535 ms - Total 58.535 ms / 1 intvls (Avg 58.535 ms)
main debug: looking for a subtitle file in /Big Media/
main debug: looking for decoder module: 32 candidates
main debug: no fetch required for expurgated (art currently (null))
main debug: no fetch required for expurgated (art currently (null))
main debug: no fetch required for expurgated (art currently (null))
avcodec debug: libavcodec initialized (interface 0x362901)
avcodec debug: trying to use direct rendering
avcodec debug: allowing 4 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 4 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 71.699 ms - Total 71.699 ms / 1 intvls (Avg 71.699 ms)
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "dts"
main debug: TIMER module_need() : 0.138 ms - Total 0.138 ms / 1 intvls (Avg 0.138 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/guykuo/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 0.536 ms - Total 0.536 ms / 1 intvls (Avg 0.536 ms)
main debug: `file:///Big%20Media/expurgated.mkv' successfully opened
dts: DTS channels:6 samplerate:48000 bitrate:1536000
main debug: creating audio output
main debug: Buffering 0%
main debug: looking for audio output module: 1 candidate
main debug: Buffering 37%
main debug: looking for text renderer module: 3 candidates
main debug: Buffering 75%
main debug: Stream buffering done (1084 ms in 1 ms)
main debug: VLC is looking for: 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
auhal debug: found 3 audio device(s)
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
auhal debug: DevID: 33 DevName: AirPlay
auhal debug: found 1 stream formats
auhal debug: supported format: [44100.000000][mcpl][12][4][1][4][2][16]
auhal debug: DevID: 50 DevName: Built-in Microphone
auhal debug: this device is INPUT only. skipping...
auhal debug: DevID: 40 DevName: Built-in Output
auhal debug: found 16 stream formats
auhal debug: supported format: [96000.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [88200.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [48000.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [44100.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [96000.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [88200.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [48000.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [44100.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [96000.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [88200.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [48000.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [44100.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [96000.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [88200.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [44100.000000][mcpl][9][8][1][8][2][32]
auhal debug: current format is: [44100.000000][mcpl][41][4][1][4][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: the actual set AU format is [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: analog output successfully opened
main debug: using audio output module "auhal"
main debug: TIMER module_need() : 12.913 ms - Total 12.913 ms / 1 intvls (Avg 12.913 ms)
main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 1.655 ms - Total 1.655 ms / 1 intvls (Avg 1.655 ms)
main debug: input 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 1.199 ms - Total 1.199 ms / 1 intvls (Avg 1.199 ms)
main debug: filter(s) 'dts '->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo
main debug: looking for audio filter module: 12 candidates
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 16.827 ms - Total 16.827 ms / 1 intvls (Avg 16.827 ms)
main debug: looking for video filter2 module: 18 candidates
main debug: using audio filter module "dtstofloat32"
main debug: TIMER module_need() : 6.083 ms - Total 6.083 ms / 1 intvls (Avg 6.083 ms)
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 12 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 14.011 ms - Total 14.011 ms / 1 intvls (Avg 14.011 ms)
main debug: looking for video filter2 module: 18 candidates
main debug: using audio filter module "ugly_resampler"
main debug: TIMER module_need() : 14.709 ms - Total 14.709 ms / 1 intvls (Avg 14.709 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 22.213 ms - Total 22.213 ms / 1 intvls (Avg 22.213 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 1 candidate
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module: 1 candidate
macosx debug: Found 1 capture devices
main debug: incoming request - stopping current input
main debug: dying input
macosx debug: prevented sleep through IOKit (5650)
macosx debug: releasing sleep blocker (5650)
macosx debug: prevented sleep through IOKit (5651)
macosx debug: returning videoview with x=0, y=36, width=1920, height=1080
main debug: using vout window nsobject module "macosx"
main debug: TIMER module_need() : 369.009 ms - Total 369.009 ms / 1 intvls (Avg 369.009 ms)
macosx debug: releasing sleep blocker (5651)
macosx debug: prevented sleep through IOKit (5652)
main debug: VoutDisplayEvent 'resize' 1920x1080 window
main debug: using vout display module "vout_macosx"
main debug: TIMER module_need() : 655.971 ms - Total 655.971 ms / 1 intvls (Avg 655.971 ms)
main debug: original format sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 0.751 ms - Total 0.751 ms / 1 intvls (Avg 0.751 ms)
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: Post-processing available
main debug: Decoder buffering done in 764 ms
main warning: PTS is out of range (-9988), dropping buffer
main warning: PTS is out of range (-20617), dropping buffer
main warning: PTS is out of range (-20601), dropping buffer
main debug: EOF reached
main warning: PTS is out of range (-31253), dropping buffer
main debug: control: stopping input
main warning: can't get output picture
main debug: dying input
avcodec warning: disabling direct rendering
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 22 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "dts"
main debug: killing decoder fourcc `dts ', 40 PES in FIFO
main debug: removing module "dtstofloat32"
main debug: removing module "scaletempo"
main debug: removing module "ugly_resampler"
main debug: removing module "auhal"
main debug: VoutDisplayEvent 'resize' 3840x2160 window
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "mkv"
main debug: Program doesn't contain anymore ES
mkv debug: Stopping the UI Hook
main debug: removing module "stream_filter_record"
main debug: removing module "filesystem"
main debug: dead input
main debug: processing request item: expurgated, node: Playlist, skip: 0
main debug: resyncing on expurgated
main debug: expurgated is at 0
main debug: starting playback of the new playlist item
main debug: resyncing on expurgated
main debug: expurgated is at 0
main debug: creating new input thread
main debug: Creating an input for 'expurgated'
main debug: using timeshift granularity of 50 MiB, in path '/tmp'
main debug: `file:///Big%20Media/expurgated.mkv' gives access `file' demux `' path `/Big%20Media/expurgated.mkv'
main debug: creating demux: access='file' demux='' location='/Big%20Media/expurgated.mkv' file='/Big Media/expurgated.mkv'
main debug: looking for access_demux module: 3 candidates
main debug: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.104 ms - Total 0.104 ms / 1 intvls (Avg 0.104 ms)
main debug: creating access 'file' location='/Big%20Media/expurgated.mkv', path='/Big Media/expurgated.mkv'
main debug: looking for access module: 3 candidates
filesystem debug: opening file `/Big Media/expurgated.mkv'
main debug: using access module "filesystem"
main debug: TIMER module_need() : 0.075 ms - Total 0.075 ms / 1 intvls (Avg 0.075 ms)
main debug: Using stream method for AStream*
main debug: starting pre-buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 47619 KiB/s
main debug: looking for stream_filter module: 7 candidates
main debug: no stream_filter module matching "any" could be loaded
main debug: TIMER module_need() : 0.051 ms - Total 0.051 ms / 1 intvls (Avg 0.051 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.044 ms - Total 0.044 ms / 1 intvls (Avg 0.044 ms)
main debug: creating demux: access='file' demux='' location='/Big%20Media/expurgated.mkv' file='/Big Media/expurgated.mkv'
main debug: looking for demux module: 55 candidates
mkv debug: | + Seek head
mkv debug: | | + Seek
mkv debug: | - info at 1186
mkv debug: | + Information
mkv debug: | | + TimecodeScale=1000000
mkv debug: | | + Muxing Application=libmakemkv v1.8.4 (1.2.0/1.1.0) darwin(x86-release)
mkv debug: | | + Writing Application=MakeMKV v1.8.4 darwin(x86-release)
mkv debug: | | + Duration=7482308
mkv debug: | | + Title=expurgated
mkv debug: | | + Date=Fri Aug 09 05:30:30 2013
mkv debug: | | + UID=-1099534721
mkv debug: | | + Seek
mkv debug: | - tracks at 1338
mkv debug: | + Tracks
mkv debug: | | + Track Entry
mkv debug: | | | + Track Number=1
mkv debug: | | | + Track UID=1
mkv debug: | | | + Track Type=video
mkv debug: | | | + Track Default=0
mkv debug: | | | + Track Lacing=0
mkv debug: | | | + Track MinCache=1
mkv debug: | | | + Track CodecId=V_MPEG4/ISO/AVC
mkv debug: | | | + Track CodecPrivate size=128
mkv debug: | | | + Track Default Duration=41708333
mkv debug: | | | + Track Video
mkv debug: | | | | + width=1920
mkv debug: | | | | + height=1080
mkv debug: | | | | + display width=1920
mkv debug: | | | | + display height=1080
mkv debug: | | + Track Entry
mkv debug: | | | + Track Number=2
mkv debug: | | | + Track UID=2
mkv debug: | | | + Track Type=audio
mkv debug: | | | + Track CodecId=A_DTS
mkv debug: | | | + Track Language=`eng'
mkv debug: | | | + Track Default Duration=10666666
mkv debug: | | | + Track Name=Lossless
mkv debug: | | | + Track Audio
mkv debug: | | | | + afreq=48000
mkv debug: | | | | + achan=8
mkv debug: | | | | + abits=24
mkv debug: | | + Track Entry
mkv debug: | | | + Track Number=3
mkv debug: | | | + Track UID=3
mkv debug: | | | + Track Type=audio
mkv debug: | | | + Track Default=0
mkv debug: | | | + Track CodecId=A_DTS
mkv debug: | | | + Track Language=`eng'
mkv debug: | | | + Track Default Duration=10666666
mkv debug: | | | + Track Name=3/2+1
mkv debug: | | | + Track Audio
mkv debug: | | | | + afreq=48000
mkv debug: | | | | + achan=6
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | | + Seek
mkv debug: | - cues at 32734904062
mkv debug: | + Cues
mkv warning: MKV/Ebml Parser: m_el[mi_level] == NULL

mkv warning: MKV/Ebml Parser: Up cannot escape itself
mkv debug: | - loading cues done.
mkv debug: | | + Seek
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | - chapters at 1692
mkv debug: | + Chapters
mkv debug: | | + EditionEntry
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9762497699572412851
mkv debug: | | | | + ChapterTimeStart: 0
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 271354416
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 01'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2450662323430459535
mkv debug: | | | | + ChapterTimeStart: 271354416
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 771812708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 02'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 6076858786740682671
mkv debug: | | | | + ChapterTimeStart: 771812708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 1285284000
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 03'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 14718225820803631619
mkv debug: | | | | + ChapterTimeStart: 1285284000
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 1640138500
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 04'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9565266583335759430
mkv debug: | | | | + ChapterTimeStart: 1640138500
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2130336541
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 05'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2956650893436651527
mkv debug: | | | | + ChapterTimeStart: 2130336541
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2406779375
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 06'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 10784866348724776462
mkv debug: | | | | + ChapterTimeStart: 2406779375
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2776023250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 07'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 16805269905506767808
mkv debug: | | | | + ChapterTimeStart: 2776023250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 3142180708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 08'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 4952233202730106261
mkv debug: | | | | + ChapterTimeStart: 3142180708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 3719716000
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 09'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 8514656931417521979
mkv debug: | | | | + ChapterTimeStart: 3719716000
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4079408666
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 10'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 15837887893723647683
mkv debug: | | | | + ChapterTimeStart: 4079408666
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4585831250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 11'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2907461212741280624
mkv debug: | | | | + ChapterTimeStart: 4585831250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4928173250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 12'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9749581052952101216
mkv debug: | | | | + ChapterTimeStart: 4928173250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5131751625
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 13'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 11636490019520421115
mkv debug: | | | | + ChapterTimeStart: 5131751625
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5422542125
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 14'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 4932000133070900316
mkv debug: | | | | + ChapterTimeStart: 5422542125
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5790367916
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 15'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2846966279178704972
mkv debug: | | | | + ChapterTimeStart: 5790367916
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6029106416
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 16'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 14433931237362077812
mkv debug: | | | | + ChapterTimeStart: 6029106416
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6387422708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 17'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2396581737502835122
mkv debug: | | | | + ChapterTimeStart: 6387422708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6785320208
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 18'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 11352521321920155113
mkv debug: | | | | + ChapterTimeStart: 6785320208
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 7103846750
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 19'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2820992798296827854
mkv debug: | | | | + ChapterTimeStart: 7103846750
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 7482308166
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 20'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | + Void
mkv debug: | + Information
mkv debug: | + Tracks
mkv debug: | + Chapters
mkv debug: | + Void
mkv debug: | + Cluster
mkv debug: Virtual chapter Chapter 01 from 0 to 271354416 -
mkv debug: Virtual chapter Chapter 02 from 271354416 to 771812708 -
mkv debug: Virtual chapter Chapter 03 from 771812708 to 1285284000 -
mkv debug: Virtual chapter Chapter 04 from 1285284000 to 1640138500 -
mkv debug: Virtual chapter Chapter 05 from 1640138500 to 2130336541 -
mkv debug: Virtual chapter Chapter 06 from 2130336541 to 2406779375 -
mkv debug: Virtual chapter Chapter 07 from 2406779375 to 2776023250 -
mkv debug: Virtual chapter Chapter 08 from 2776023250 to 3142180708 -
mkv debug: Virtual chapter Chapter 09 from 3142180708 to 3719716000 -
mkv debug: Virtual chapter Chapter 10 from 3719716000 to 4079408666 -
mkv debug: Virtual chapter Chapter 11 from 4079408666 to 4585831250 -
mkv debug: Virtual chapter Chapter 12 from 4585831250 to 4928173250 -
mkv debug: Virtual chapter Chapter 13 from 4928173250 to 5131751625 -
mkv debug: Virtual chapter Chapter 14 from 5131751625 to 5422542125 -
mkv debug: Virtual chapter Chapter 15 from 5422542125 to 5790367916 -
mkv debug: Virtual chapter Chapter 16 from 5790367916 to 6029106416 -
mkv debug: Virtual chapter Chapter 17 from 6029106416 to 6387422708 -
mkv debug: Virtual chapter Chapter 18 from 6387422708 to 6785320208 -
mkv debug: Virtual chapter Chapter 19 from 6785320208 to 7103846750 -
mkv debug: Virtual chapter Chapter 20 from 7103846750 to 7482308166 -
mkv debug: Virtual chapter from 0 to -1 -
mkv debug: found 3 es
main debug: selecting program id=0
mkv debug: Starting the UI Hook
main debug: using demux module "mkv"
main debug: TIMER module_need() : 44.620 ms - Total 44.620 ms / 1 intvls (Avg 44.620 ms)
main debug: looking for a subtitle file in /Big Media/
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec already initialized
avcodec debug: trying to use direct rendering
avcodec debug: allowing 4 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 4 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 0.650 ms - Total 0.650 ms / 1 intvls (Avg 0.650 ms)
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "dts"
main debug: TIMER module_need() : 0.091 ms - Total 0.091 ms / 1 intvls (Avg 0.091 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in /Users/guykuo/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Applications/VLC.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Applications/VLC.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Applications/VLC.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 0.437 ms - Total 0.437 ms / 1 intvls (Avg 0.437 ms)
main debug: `file:///Big%20Media/expurgated.mkv' successfully opened
dts: DTS channels:6 samplerate:48000 bitrate:1536000
main debug: recycling audio output
main debug: looking for audio output module: 1 candidate
main debug: Buffering 0%
main debug: VLC is looking for: 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
auhal debug: found 3 audio device(s)
main debug: Buffering 37%
main debug: trying to reuse free vout
main debug: removing module "freetype"
main debug: looking for text renderer module: 3 candidates
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
main debug: Buffering 75%
auhal debug: DevID: 33 DevName: AirPlay
auhal debug: found 1 stream formats
auhal debug: supported format: [44100.000000][mcpl][12][4][1][4][2][16]
freetype debug: using fontsize: 2
auhal debug: DevID: 50 DevName: Built-in Microphone
main debug: using text renderer module "freetype"
auhal debug: this device is INPUT only. skipping...
main debug: TIMER module_need() : 1.030 ms - Total 1.030 ms / 1 intvls (Avg 1.030 ms)
main debug: Stream buffering done (1084 ms in 1 ms)
main debug: reusing provided vout
avcodec debug: using direct rendering
auhal debug: DevID: 40 DevName: Built-in Output
auhal debug: found 16 stream formats
auhal debug: supported format: [96000.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [88200.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [48000.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [44100.000000][mcpl][4][8][1][8][2][24]
auhal debug: supported format: [96000.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [88200.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [48000.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [44100.000000][mcpl][4][8][1][8][2][20]
auhal debug: supported format: [96000.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [88200.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [48000.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [44100.000000][mcpl][12][4][1][4][2][16]
auhal debug: supported format: [96000.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [88200.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: supported format: [44100.000000][mcpl][9][8][1][8][2][32]
auhal warning: failed to add listener for audio device configuration (1852797029)
auhal warning: audio device already in use
auhal warning: could not set alive check callback on device [0x28]: epon
auhal debug: current format is: [44100.000000][mcpl][41][4][1][4][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: the actual set AU format is [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: analog output successfully opened
main debug: using audio output module "auhal"
main debug: TIMER module_need() : 6.120 ms - Total 6.120 ms / 1 intvls (Avg 6.120 ms)
main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.149 ms - Total 0.149 ms / 1 intvls (Avg 0.149 ms)
main debug: input 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.160 ms - Total 0.160 ms / 1 intvls (Avg 0.160 ms)
main debug: filter(s) 'dts '->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo
main debug: looking for audio filter module: 12 candidates
main debug: using audio filter module "dtstofloat32"
main debug: TIMER module_need() : 0.125 ms - Total 0.125 ms / 1 intvls (Avg 0.125 ms)
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 12 candidates
main debug: using audio filter module "ugly_resampler"
main debug: TIMER module_need() : 0.057 ms - Total 0.057 ms / 1 intvls (Avg 0.057 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
macosx debug: releasing sleep blocker (5652)
macosx debug: prevented sleep through IOKit (5655)
main debug: TIMER input launching for 'expurgated.mkv' : 132.003 ms - Total 132.003 ms / 1 intvls (Avg 132.003 ms)
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: Decoder buffering done in 69 ms
main warning: PTS is out of range (-9966), dropping buffer
main warning: PTS is out of range (-20580), dropping buffer
main warning: PTS is out of range (-20552), dropping buffer
main warning: PTS is out of range (-31187), dropping buffer
macosx debug: no optical media found
macosx debug: will set filter 'sharpen'
main debug: Adding 'sharpen' as interactive
main debug: looking for video filter2 module: 1 candidate
main debug: using video filter2 module "sharpen"
main debug: TIMER module_need() : 3.004 ms - Total 3.004 ms / 1 intvls (Avg 3.004 ms)
main debug: Filter 'sharpen' (0x1028392f0) appended to chain

[kdean]

JB, this is also likely due to the over twice as much cpu usage (vs 2.0.8 ) with sharpen turned on that I've reported at: https://trac.videolan.org/vlc/ticket/9458

Dropped frames issues is how I first noticed which lead me to notice the cpu usage problem.

[guykuo]

Yes, something in 2.1.0's new video processing chain is causing it + sharpen to take much longer to render frames. If anything, 2.1.0 on a Mac now increases the minimal CPU needed to play back with sharpening to something like a 6.0 GHz quad core - something that isn't in any existing Mac.

For now, it is best to stay with 2.0.8 and avoid 2.1.0 if image sharpening is needed. You simply can't use it under 2.1.0 when playing back 1080 materials

Hopefully, this was a regression in the sharpen filter handler that can be fixed.

[Jean-Baptiste Kempf]

look if no new filter got in the middle.

[guykuo]

How does one check which filters are turned on in the playback chain?

(I did reset all settings and I don't see any other filters checked in the all settings video dialog)

BTW, this problem is present in the nightly builds for 2.1.1 and 2.2.0. Appears to be pretty well embedded in the current code.
Makes sharpening filter unusable on Macs for HD material.

[Jean-Baptiste Kempf]

Look in the logs.

[guykuo]

OK. Must not be adding another filter. Something in the normal render chain + the sharpen filter ---> too longer to render and deliver frames in time.

1. Look in the logs...

2. Posted the log above.

3. Asked how to verify filters

4. Look in the logs...

5. Log is above. Nothing about another filter interposed.

[Jean-Baptiste Kempf]

The logs are cut before they start being interesting, to be honest.

[guykuo]

Log is longer this time. Don't know why it was so short before.
Also, noted that deinterlace turns on with blend. Turning off deinterlace from menu doesn't stop the frame drops.


mkv debug: | | | + Track Number=3
mkv debug: | | | + Track Type=audio
mkv debug: | | | + Track Default=0
mkv debug: | | | + Track CodecId=A_DTS
mkv debug: | | | + Track Language=`eng'
mkv debug: | | | + Track Default Duration=10666666
mkv debug: | | | + Track Name=3/2+1
mkv debug: | | | + Track Audio
mkv debug: | | | | + afreq=48000
mkv debug: | | | | + achan=6
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | | + Seek
mkv debug: | - cues at 32734904062
mkv debug: | + Cues
mkv warning: MKV/Ebml Parser: m_el[mi_level] == NULL

mkv warning: MKV/Ebml Parser: Up cannot escape itself
mkv debug: | - loading cues done.
mkv debug: | | + Seek
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | | + Unknown (N7libebml8EbmlVoidE)
mkv debug: | - chapters at 1692
mkv debug: | + Chapters
mkv debug: | | + EditionEntry
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9762497699572412851
mkv debug: | | | | + ChapterTimeStart: 0
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 271354416
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 01'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2450662323430459535
mkv debug: | | | | + ChapterTimeStart: 271354416
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 771812708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 02'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 6076858786740682671
mkv debug: | | | | + ChapterTimeStart: 771812708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 1285284000
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 03'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 14718225820803631619
mkv debug: | | | | + ChapterTimeStart: 1285284000
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 1640138500
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 04'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9565266583335759430
mkv debug: | | | | + ChapterTimeStart: 1640138500
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2130336541
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 05'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2956650893436651527
mkv debug: | | | | + ChapterTimeStart: 2130336541
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2406779375
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 06'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 10784866348724776462
mkv debug: | | | | + ChapterTimeStart: 2406779375
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 2776023250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 07'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 16805269905506767808
mkv debug: | | | | + ChapterTimeStart: 2776023250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 3142180708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 08'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 4952233202730106261
mkv debug: | | | | + ChapterTimeStart: 3142180708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 3719716000
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 09'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 8514656931417521979
mkv debug: | | | | + ChapterTimeStart: 3719716000
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4079408666
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 10'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 15837887893723647683
mkv debug: | | | | + ChapterTimeStart: 4079408666
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4585831250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 11'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2907461212741280624
mkv debug: | | | | + ChapterTimeStart: 4585831250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 4928173250
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 12'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 9749581052952101216
mkv debug: | | | | + ChapterTimeStart: 4928173250
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5131751625
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 13'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 11636490019520421115
mkv debug: | | | | + ChapterTimeStart: 5131751625
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5422542125
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 14'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 4932000133070900316
mkv debug: | | | | + ChapterTimeStart: 5422542125
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 5790367916
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 15'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2846966279178704972
mkv debug: | | | | + ChapterTimeStart: 5790367916
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6029106416
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 16'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 14433931237362077812
mkv debug: | | | | + ChapterTimeStart: 6029106416
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6387422708
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 17'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2396581737502835122
mkv debug: | | | | + ChapterTimeStart: 6387422708
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 6785320208
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 18'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 11352521321920155113
mkv debug: | | | | + ChapterTimeStart: 6785320208
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 7103846750
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 19'
mkv debug: | | | | | + ChapterLanguage 'eng'
mkv debug: | | | + ChapterAtom (level=0)
mkv debug: | | | | + ChapterUID: 2820992798296827854
mkv debug: | | | | + ChapterTimeStart: 7103846750
mkv debug: | | | | + ChapterFlagHidden: no
mkv debug: | | | | + ChapterTimeEnd: 7482308166
mkv debug: | | | | + ChapterDisplay
mkv debug: | | | | | + ChapterString 'Chapter 20'
mkv debug: | | | | | + ChapterLanguage 'eng'
main debug: no fetch required for expunged (art currently (null))
mkv debug: | + Void
main debug: no fetch required for expunged (art currently (null))
mkv debug: | + Information
main debug: no fetch required for expunged (art currently (null))
mkv debug: | + Tracks
main debug: no fetch required for expunged (art currently (null))
mkv debug: | + Chapters
mkv debug: | + Void
mkv debug: | + Cluster
mkv debug: Virtual chapter Chapter 01 from 0 to 271354416 -
mkv debug: Virtual chapter Chapter 02 from 271354416 to 771812708 -
mkv debug: Virtual chapter Chapter 03 from 771812708 to 1285284000 -
mkv debug: Virtual chapter Chapter 04 from 1285284000 to 1640138500 -
mkv debug: Virtual chapter Chapter 05 from 1640138500 to 2130336541 -
mkv debug: Virtual chapter Chapter 06 from 2130336541 to 2406779375 -
mkv debug: Virtual chapter Chapter 07 from 2406779375 to 2776023250 -
mkv debug: Virtual chapter Chapter 08 from 2776023250 to 3142180708 -
mkv debug: Virtual chapter Chapter 09 from 3142180708 to 3719716000 -
mkv debug: Virtual chapter Chapter 10 from 3719716000 to 4079408666 -
mkv debug: Virtual chapter Chapter 11 from 4079408666 to 4585831250 -
mkv debug: Virtual chapter Chapter 12 from 4585831250 to 4928173250 -
mkv debug: Virtual chapter Chapter 13 from 4928173250 to 5131751625 -
mkv debug: Virtual chapter Chapter 14 from 5131751625 to 5422542125 -
mkv debug: Virtual chapter Chapter 15 from 5422542125 to 5790367916 -
mkv debug: Virtual chapter Chapter 16 from 5790367916 to 6029106416 -
mkv debug: Virtual chapter Chapter 17 from 6029106416 to 6387422708 -
mkv debug: Virtual chapter Chapter 18 from 6387422708 to 6785320208 -
mkv debug: Virtual chapter Chapter 19 from 6785320208 to 7103846750 -
mkv debug: Virtual chapter Chapter 20 from 7103846750 to 7482308166 -
mkv debug: Virtual chapter from 0 to -1 -
mkv debug: found 3 es
main debug: selecting program id=0
main debug: Buffering 0%
mkv debug: Starting the UI Hook
main debug: using demux module "mkv"
main debug: looking for a subtitle file in /Big Media/
main debug: looking for decoder module matching "any": 37 candidates
avcodec debug: trying to use direct rendering
avcodec debug: allowing 4 thread(s) for decoding
avcodec debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 4 threads
main debug: using decoder module "avcodec"
main debug: looking for decoder module matching "any": 37 candidates
main debug: using decoder module "dts"
main debug: looking for meta reader module matching "any": 2 candidates
lua debug: Trying Lua scripts in /Users/guykuo/Library/Application Support/org.videolan.vlc/lua/meta/reader
lua debug: Trying Lua scripts in /Users/guykuo/Desktop/VLC 2.1.0.app/Contents/MacOS/share/lua/meta/reader
lua debug: Trying Lua playlist script /Users/guykuo/Desktop/VLC 2.1.0.app/Contents/MacOS/share/lua/meta/reader/filename.luac
lua debug: Trying Lua scripts in /Users/guykuo/Desktop/VLC 2.1.0.app/Contents/MacOS/share/share/lua/meta/reader
main debug: no meta reader modules matched
main debug: `file:///Big%20Media/expunged.mkv' successfully opened
dts: DTS channels:6 samplerate:48000 bitrate:1536000
main debug: reusing audio output
main debug: VLC is looking for: 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
auhal debug: attempting to use device 0
main debug: Buffering 0%
main debug: looking for text renderer module matching "any": 3 candidates
auhal debug: using default audio device 40
main debug: Buffering 33%
auhal debug: found 16 stream formats for stream id 41
freetype debug: looking for Arial Unicode MS
auhal debug: Audio device supports PCM mode only
main debug: Buffering 66%
main debug: Buffering 99%
main debug: Stream buffering done (1335 ms in 12 ms)
auhal debug: current format is: [44100.000000][mcpl][9][8][1][8][2][32]
auhal debug: layout of AUHAL has 2 channels
auhal debug: selected 2 physical channels for device output
auhal debug: VLC will output: Stereo
auhal debug: we set the AU format: [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: the actual set AU format is [48000.000000][mcpl][9][8][1][8][2][32]
auhal debug: analog output successfully opened
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 'dts ' 48000 Hz 3F2R/LFE frame=512 samples/2048 bytes
main debug: looking for audio filter module matching "scaletempo": 14 candidates
scaletempo debug: format: 48000 rate, 6 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: conversion: 'dts '->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->3F2R/LFE
main debug: looking for audio converter module matching "any": 11 candidates
main debug: no audio converter modules matched
main debug: looking for audio converter module matching "any": 11 candidates
main debug: using audio converter module "dtstofloat32"
main debug: conversion pipeline complete
main debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz 3F2R/LFE->Stereo
main debug: looking for audio converter module matching "any": 11 candidates
main debug: using audio converter module "simple_channel_mixer"
main debug: conversion pipeline complete
main debug: looking for audio resampler module matching "any": 2 candidates
main debug: using audio resampler module "ugly_resampler"
main debug: End of audio preroll
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: looking for video filter2 module matching "any": 54 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: looking for video filter2 module matching "any": 54 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: Deinterlacing available
main debug: deinterlace 1, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module matching "any": 5 candidates
vout_macosx debug: Quartz Extreme acceleration is active
main debug: looking for vout window nsobject module matching "any": 1 candidates
macosx debug: prevented sleep through IOKit (888)
macosx debug: returning videoview with proposed position x=0, y=0, width=1920, height=1080
main debug: using vout window nsobject module "macosx"
macosx debug: releasing old sleep blocker (888)
macosx debug: prevented sleep through IOKit (890)
main debug: VoutDisplayEvent 'resize' 1920x1080 window
main debug: using vout display module "vout_macosx"
main debug: original format sz 1920x1080, of (0,0), vsz 1920x1080, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0
main debug: Adding 'deinterlace' as static
main debug: looking for video filter2 module matching "deinterlace": 54 candidates
deinterlace debug: using blend deinterlace method
deinterlace debug: deinterlacing
main debug: using video filter2 module "deinterlace"
main debug: Filter 'deinterlace' (0x1068f84b0) appended to chain
main debug: Adding 'sharpen' as interactive
main debug: looking for video filter2 module matching "sharpen": 54 candidates
main debug: using video filter2 module "sharpen"
main debug: Filter 'sharpen' (0x1068f8ab0) appended to chain
main debug: removing module "freetype"
main debug: looking for text renderer module matching "any": 3 candidates
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
freetype debug: Using Arial Unicode MS as font from file /Library/Fonts/Arial Unicode.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
avcodec debug: using direct rendering
main debug: VoutDisplayEvent 'resize' 3840x2216 window
main debug: End of video preroll
main debug: Received first picture
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: VoutDisplayEvent 'resize' 3840x2216 window
main debug: VoutDisplayEvent 'resize' 3840x2216 window
main debug: VoutDisplayEvent 'resize' 3840x2216 window
main debug: VoutDisplayEvent 'resize' 3840x2190 window
main debug: Decoder buffering done in 316 ms
main debug: picture might be displayed late (missing 17 ms)
main debug: VoutDisplayEvent 'resize' 3840x2190 window
main debug: VoutDisplayEvent 'resize' 3840x2190 window
main debug: VoutDisplayEvent 'resize' 3840x2190 window
main debug: VoutDisplayEvent 'resize' 3840x2180 window
main warning: picture is too late to be displayed (missing 44 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: VoutDisplayEvent 'resize' 3840x2180 window
main debug: VoutDisplayEvent 'resize' 3840x2180 window
main debug: VoutDisplayEvent 'resize' 3840x2180 window
main debug: VoutDisplayEvent 'resize' 3840x2174 window
main debug: picture might be displayed late (missing 8 ms)
main debug: VoutDisplayEvent 'resize' 3840x2174 window
main debug: VoutDisplayEvent 'resize' 3840x2174 window
main debug: VoutDisplayEvent 'resize' 3840x2174 window
main debug: VoutDisplayEvent 'resize' 3840x2164 window
main debug: picture might be displayed late (missing 13 ms)
main debug: VoutDisplayEvent 'resize' 3840x2164 window
main debug: VoutDisplayEvent 'resize' 3840x2164 window
main debug: VoutDisplayEvent 'resize' 3840x2164 window
main debug: VoutDisplayEvent 'resize' 3840x2160 window
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 71 ms)
main warning: picture is too late to be displayed (missing 29 ms)
freetype debug: looking for Arial Unicode MS
freetype debug: found /Library/Fonts/Arial Unicode.ttf
main debug: picture might be displayed late (missing 3 ms)
main debug: picture might be displayed late (missing 8 ms)
main debug: picture might be displayed late (missing 12 ms)
main debug: picture might be displayed late (missing 15 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 1 ms)
main debug: picture might be displayed late (missing 4 ms)
main warning: picture is too late to be displayed (missing 46 ms)
main debug: picture might be displayed late (missing 5 ms)
main debug: picture might be displayed late (missing 8 ms)
main debug: picture might be displayed late (missing 12 ms)
main debug: picture might be displayed late (missing 16 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 6 ms)
main debug: picture might be displayed late (missing 8 ms)
main debug: picture might be displayed late (missing 12 ms)
main warning: picture is too late to be displayed (missing 41 ms)
main debug: picture might be displayed late (missing 7 ms)
main debug: picture might be displayed late (missing 10 ms)
main debug: picture might be displayed late (missing 15 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 5 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 11 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 24 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main warning: picture is too late to be displayed (missing 2616 ms)
main warning: picture is too late to be displayed (missing 2681 ms)
main warning: picture is too late to be displayed (missing 2640 ms)
main warning: picture is too late to be displayed (missing 2653 ms)
main warning: picture is too late to be displayed (missing 2611 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 6 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 46 ms)
main debug: picture might be displayed late (missing 4 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 39 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main warning: picture is too late to be displayed (missing 67 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 35 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 27 ms)
main debug: picture might be displayed late (missing 12 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 7 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 1 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 3 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main debug: picture might be displayed late (missing 3 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main debug: picture might be displayed late (missing 11 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 1 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: picture is too late to be displayed (missing 28 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 35 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main debug: picture might be displayed late (missing 3 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main debug: picture might be displayed late (missing 2 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 6 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 35 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 24 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 34 ms)
main debug: picture might be displayed late (missing 7 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main debug: picture might be displayed late (missing 7 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 44 ms)
main debug: picture might be displayed late (missing 3 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main debug: picture might be displayed late (missing 6 ms)
main warning: picture is too late to be displayed (missing 34 ms)
main debug: picture might be displayed late (missing 7 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 45 ms)
main debug: picture might be displayed late (missing 3 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 32 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 37 ms)
main warning: picture is too late to be displayed (missing 24 ms)
main warning: picture is too late to be displayed (missing 53 ms)
main debug: picture might be displayed late (missing 12 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 2 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 7 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 46 ms)
main debug: picture might be displayed late (missing 4 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 4 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 41 ms)
main debug: picture might be displayed late (missing 0 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 27 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 12 ms)
main warning: picture is too late to be displayed (missing 28 ms)
main warning: picture is too late to be displayed (missing 27 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 43 ms)
main debug: picture might be displayed late (missing 1 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main debug: picture might be displayed late (missing 2 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 4 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 47 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main warning: picture is too late to be displayed (missing 24 ms)
main debug: picture might be displayed late (missing 11 ms)
main warning: picture is too late to be displayed (missing 39 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 19 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 40 ms)
main debug: picture might be displayed late (missing 12 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 1 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main debug: picture might be displayed late (missing 13 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 0 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 15 ms)
main warning: picture is too late to be displayed (missing 45 ms)
main debug: picture might be displayed late (missing 4 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 7 ms)
main warning: picture is too late to be displayed (missing 20 ms)
main warning: picture is too late to be displayed (missing 23 ms)
main debug: picture might be displayed late (missing 9 ms)
main warning: picture is too late to be displayed (missing 40 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 31 ms)
main debug: picture might be displayed late (missing 7 ms)
main warning: picture is too late to be displayed (missing 22 ms)
main warning: picture is too late to be displayed (missing 24 ms)
main debug: picture might be displayed late (missing 17 ms)
main warning: picture is too late to be displayed (missing 47 ms)
main debug: picture might be displayed late (missing 6 ms)
main warning: picture is too late to be displayed (missing 21 ms)
main debug: picture might be displayed late (missing 10 ms)
main warning: picture is too late to be displayed (missing 26 ms)
main warning: picture is too late to be displayed (missing 28 ms)
main debug: picture might be displayed late (missing 1 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: picture is too late to be displayed (missing 44 ms)
main debug: picture might be displayed late (missing 2 ms)
main debug: picture might be displayed late (missing 18 ms)
main warning: picture is too late to be displayed (missing 30 ms)
main debug: picture might be displayed late (missing 7 ms)
main warning: picture is too late to be displayed (missing 25 ms)
main warning: picture is too late to be displayed (missing 26 ms)