Page 1 of 1

TVOS Video display failure

Posted: 01 Jun 2017 21:47
by naftaly
Hi, I'm working on an TVOS app that uses MobileVLCKit. I've run into a problem where the sound plays fine but I get no video. The weird thing is that when in xCode, if I hit pause in the debugger then continue again, the video will start playing. I've tried many different options but I always get the same result. I've attached the log. Any help greatly appreciated.

regards,
AC

Code: Select all

2017-06-01 15:00:07.632 Leftenant[80698:3490841] creating player instance with private library as options were given 2017-06-01 15:00:07.638 Leftenant[80698:3490841] VLC media player - 3.0.0-git Vetinari 2017-06-01 15:00:07.638 Leftenant[80698:3490841] Copyright © 1996-2017 the VideoLAN team 2017-06-01 15:00:07.639 Leftenant[80698:3490841] revision 2.2.0-git-12413-g6e9819cef2 2017-06-01 15:00:07.639 Leftenant[80698:3490841] configured with /Users/alex/Documents/Code/GitHub/VLCKit/libvlc/vlc/configure '--prefix=/Users/alex/Documents/Code/GitHub/VLCKit/libvlc/vlc/install-AppleTVSimulator/x86_64' '--host=x86_64-apple-darwin14' '--with-contrib=/Users/alex/Documents/Code/GitHub/VLCKit/libvlc/vlc/contrib/AppleTV-x86_64-apple-darwin14-x86_64' '--enable-static' '--disable-debug' '--enable-dvbpsi' '--enable-avcodec' '--disable-vpx' '--disable-neon' '--disable-macosx' '--disable-macosx-qtkit' '--disable-macosx-avfoundation' '--disable-shared' '--enable-opus' '--disable-faad' '--disable-lua' '--disable-a52' '--enable-fribidi' '--disable-qt' '--disable-skins2' '--disable-vcd' '--disable-vlc' '--disable-vlm' '--disable-httpd' '--disable-nls' '--disable-sse' '--disable-notify' '--enable-live555' '--enable-realrtsp' '--enable-swscale' '--disable-projectm' '--enable-libass' '--enable-libxml2' '--disable-goom' '--disable-dvdread' '--disable-dvdnav' '--disable-bluray' '--disable-linsys' '--disable-libva' '--disable-gme' '--disable-tremor' '--enable-vorbis' '--disable-fluidsynth' '--disable-jack' '--disable-pulse' '--disable-mtp' '--enable-ogg' '--enable-speex' '--enable-theora' '--enable-flac' '--disable-screen' '--enable-freetype' '--enable-taglib' '--disable-mmx' '--disable-addonmanagermodules' '--disable-mad' 'host_alias=x86_64-apple-darwin14' 'CC=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang' 'CFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/AppleTVSimulator.platform/Developer/SDKs/AppleTVSimulator10.2.sdk -arch x86_64 -O3 -g -mtvos-version-min=9.0 -fembed-bitcode' 'LDFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/AppleTVSimulator.platform/Developer/SDKs/AppleTVSimulator10.2.sdk -L/Applications/Xcode.app/Contents/Developer/Platforms/AppleTVSimulator.platform/Developer/SDKs/AppleTVSimulator10.2.sdk/usr/lib -arch x86_64 -v -Wl,-tvos_version_min,9.0' 'CPPFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/AppleTVSimulator.platform/Developer/SDKs/AppleTVSimulator10.2.sdk -arch x86_64 -O3 -g -mtvos-version-min=9.0 -fembed-bitcode' 'CXX=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang++' 'CXXFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/AppleTVSimulator.platform/Developer/SDKs/AppleTVSimulator10.2.sdk -arch x86_64 -O3 -g -mtvos-version-min=9.0 -fembed-bitcode' 'OBJC=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang' 'OBJCFLAGS=-fobjc-abi-version=2 -fobjc-legacy-dispatch -O3 -g' 2017-06-01 15:00:46.948 Leftenant[80698:3491612] Creating an input for 'video.mp4' 2017-06-01 15:00:46.948 Leftenant[80698:3490841] movie state: 2017-06-01 15:00:46.948 Leftenant[80698:3491612] [VLCMedia] HandleMediaMetaChanged: nowPlaying 2017-06-01 15:00:46.949 Leftenant[80698:3491612] [VLCMedia] HandleMediaMetaChanged: nowPlaying 2017-06-01 15:00:46.949 Leftenant[80698:3491612] [VLCMedia] HandleMediaMetaChanged: artworkURL 2017-06-01 15:00:46.949 Leftenant[80698:3490841] isPlaying: 0 2017-06-01 15:00:46.949 Leftenant[80698:3491613] using timeshift granularity of 50 MiB 2017-06-01 15:00:46.949 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:00:46.949 Leftenant[80698:3491613] using default timeshift path 2017-06-01 15:00:46.949 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:00:46.949 Leftenant[80698:3491613] [VLCMedia] HandleMediaStateChanged: 0 2017-06-01 15:00:46.949 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:00:46.950 Leftenant[80698:3490841] state: VLCMediaPlayerStateStopped 2017-06-01 15:00:46.950 Leftenant[80698:3491613] `file:///Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' gives access `file' demux `any' path `/Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' 2017-06-01 15:00:46.950 Leftenant[80698:3491613] creating demux: access='file' demux='any' location='/Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' file='/Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' 2017-06-01 15:00:46.950 Leftenant[80698:3491613] looking for access_demux module matching "file": 3 candidates 2017-06-01 15:00:46.950 Leftenant[80698:3491613] no access_demux modules matched 2017-06-01 15:00:46.951 Leftenant[80698:3491613] creating access: file:///Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4 2017-06-01 15:00:46.951 Leftenant[80698:3491613] (path: /Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4) 2017-06-01 15:00:46.951 Leftenant[80698:3491613] looking for access module matching "file": 18 candidates 2017-06-01 15:00:46.951 Leftenant[80698:3491613] using access module "filesystem" 2017-06-01 15:00:46.952 Leftenant[80698:3491613] looking for stream_filter module matching "prefetch,cache_read": 7 candidates 2017-06-01 15:00:46.952 Leftenant[80698:3491613] Using stream method for AStream* 2017-06-01 15:00:46.952 Leftenant[80698:3491613] starting pre-buffering 2017-06-01 15:00:46.952 Leftenant[80698:3491613] received first data after 0 ms 2017-06-01 15:00:46.952 Leftenant[80698:3491613] pre-buffering done 1024 bytes in 0s - 2481 KiB/s 2017-06-01 15:00:46.952 Leftenant[80698:3491613] using stream_filter module "cache_read" 2017-06-01 15:00:46.953 Leftenant[80698:3491613] looking for stream_filter module matching "any": 7 candidates 2017-06-01 15:00:46.953 Leftenant[80698:3491613] no stream_filter modules matched 2017-06-01 15:00:46.953 Leftenant[80698:3491613] looking for stream_directory module matching "any": 0 candidates 2017-06-01 15:00:46.953 Leftenant[80698:3491613] no stream_directory modules 2017-06-01 15:00:46.953 Leftenant[80698:3491613] attach of directory extractor failed 2017-06-01 15:00:46.953 Leftenant[80698:3490841] mediaPlayerStateChanged: VLCMediaPlayerStateBuffering 2017-06-01 15:00:46.953 Leftenant[80698:3491613] looking for stream_filter module matching "record": 7 candidates 2017-06-01 15:00:46.954 Leftenant[80698:3491613] using stream_filter module "record" 2017-06-01 15:00:46.954 Leftenant[80698:3490841] movie state: 2017-06-01 15:00:46.954 Leftenant[80698:3491613] creating demux: access='file' demux='any' location='/Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' file='/Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' 2017-06-01 15:00:46.954 Leftenant[80698:3490841] isPlaying: 0 2017-06-01 15:00:46.954 Leftenant[80698:3491613] looking for demux module matching "mp4": 60 candidates 2017-06-01 15:00:46.954 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:00:46.954 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:00:46.954 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:00:46.955 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:00:46.969 Leftenant[80698:3491613] dumping root Box "root" 2017-06-01 15:00:46.969 Leftenant[80698:3491613] | + ftyp size 32 offset 0 2017-06-01 15:00:46.970 Leftenant[80698:3491613] | + moov size 5475143 offset 32 2017-06-01 15:00:46.970 Leftenant[80698:3491613] | | + mvhd size 108 offset 40 2017-06-01 15:00:46.970 Leftenant[80698:3491613] | | + trak size 2376393 offset 148 2017-06-01 15:00:46.970 Leftenant[80698:3491613] | | | + tkhd size 92 offset 156 2017-06-01 15:00:46.970 Leftenant[80698:3491613] | | | + edts size 36 offset 248 2017-06-01 15:00:46.971 Leftenant[80698:3491613] | | | | + elst size 28 offset 256 2017-06-01 15:00:46.971 Leftenant[80698:3491613] | | | + mdia size 2376257 offset 284 2017-06-01 15:00:46.971 Leftenant[80698:3491613] | | | | + mdhd size 32 offset 292 2017-06-01 15:00:46.971 Leftenant[80698:3491613] | | | | + hdlr size 45 offset 324 2017-06-01 15:00:46.971 Leftenant[80698:3491613] | | | | + minf size 2376172 offset 369 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | + vmhd size 20 offset 377 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | + dinf size 36 offset 397 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | | + dref size 28 offset 405 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | | | + url size 12 offset 421 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | + stbl size 2376108 offset 433 2017-06-01 15:00:46.972 Leftenant[80698:3491613] | | | | | | + stsd size 172 offset 441 2017-06-01 15:00:46.973 Leftenant[80698:3491613] | | | | | | | + avc1 size 156 offset 457 2017-06-01 15:00:46.973 Leftenant[80698:3491613] | | | | | | | | + avcC size 54 offset 543 2017-06-01 15:00:46.973 Leftenant[80698:3491613] | | | | | | | | + pasp size 16 offset 597 2017-06-01 15:00:46.973 Leftenant[80698:3491613] | | | | | | + stts size 24 offset 613 2017-06-01 15:00:46.973 Leftenant[80698:3491613] | | | | | | + stss size 7168 offset 637 2017-06-01 15:00:46.974 Leftenant[80698:3491613] | | | | | | + ctts size 1166016 offset 7805 2017-06-01 15:00:46.974 Leftenant[80698:3491613] | | | | | | + stsc size 28 offset 1173821 2017-06-01 15:00:46.974 Leftenant[80698:3491613] | | | | | | + stsz size 601348 offset 1173849 2017-06-01 15:00:46.974 Leftenant[80698:3491613] | | | | | | + stco size 601344 offset 1775197 2017-06-01 15:00:46.974 Leftenant[80698:3491613] | | + trak size 3098536 offset 2376541 2017-06-01 15:00:46.975 Leftenant[80698:3491613] | | | + tkhd size 92 offset 2376549 2017-06-01 15:00:46.975 Leftenant[80698:3491613] | | | + edts size 36 offset 2376641 2017-06-01 15:00:46.975 Leftenant[80698:3491613] | | | | + elst size 28 offset 2376649 2017-06-01 15:00:46.975 Leftenant[80698:3491613] | | | + mdia size 3098400 offset 2376677 2017-06-01 15:00:46.975 Leftenant[80698:3491613] | | | | + mdhd size 32 offset 2376685 2017-06-01 15:00:46.976 Leftenant[80698:3491613] | | | | + hdlr size 45 offset 2376717 2017-06-01 15:00:46.976 Leftenant[80698:3491613] | | | | + minf size 3098315 offset 2376762 2017-06-01 15:00:46.976 Leftenant[80698:3491613] | | | | | + smhd size 16 offset 2376770 2017-06-01 15:00:46.976 Leftenant[80698:3491613] | | | | | + dinf size 36 offset 2376786 2017-06-01 15:00:46.976 Leftenant[80698:3491613] | | | | | | + dref size 28 offset 2376794 2017-06-01 15:00:46.977 Leftenant[80698:3491613] | | | | | | | + url size 12 offset 2376810 2017-06-01 15:00:46.977 Leftenant[80698:3491613] | | | | | + stbl size 3098255 offset 2376822 2017-06-01 15:00:46.977 Leftenant[80698:3491613] | | | | | | + stsd size 103 offset 2376830 2017-06-01 15:00:46.977 Leftenant[80698:3491613] | | | | | | | + mp4a size 87 offset 2376846 2017-06-01 15:00:46.978 Leftenant[80698:3491613] | | | | | | | | + esds size 51 offset 2376882 2017-06-01 15:00:46.978 Leftenant[80698:3491613] | | | | | | + stts size 1159016 offset 2376933 2017-06-01 15:00:46.978 Leftenant[80698:3491613] | | | | | | + stsc size 162112 offset 3535949 2017-06-01 15:00:46.978 Leftenant[80698:3491613] | | | | | | + stsz size 1175672 offset 3698061 2017-06-01 15:00:46.978 Leftenant[80698:3491613] | | | | | | + stco size 601344 offset 4873733 2017-06-01 15:00:46.979 Leftenant[80698:3491613] | | + udta size 98 offset 5475077 2017-06-01 15:00:46.979 Leftenant[80698:3491613] | | | + meta size 90 offset 5475085 2017-06-01 15:00:46.979 Leftenant[80698:3491613] | | | | + hdlr size 33 offset 5475097 2017-06-01 15:00:46.979 Leftenant[80698:3491613] | | | | + ilst size 45 offset 5475130 2017-06-01 15:00:46.979 Leftenant[80698:3491613] | | | | | + ctoo size 37 offset 5475138 2017-06-01 15:00:46.980 Leftenant[80698:3491613] | | | | | | + data size 29 offset 5475146 2017-06-01 15:00:46.980 Leftenant[80698:3491613] | + free size 8 offset 5475175 2017-06-01 15:00:46.980 Leftenant[80698:3491613] | + mdat size 796084293 offset 5475183 2017-06-01 15:00:46.980 Leftenant[80698:3491613] ISO Media (isom) version 512. 2017-06-01 15:00:46.981 Leftenant[80698:3491613] found 2 tracks 2017-06-01 15:00:46.981 Leftenant[80698:3491613] elst box found 2017-06-01 15:00:46.981 Leftenant[80698:3491613] - [0] duration=6270098ms media time=83ms) rate=1.0 2017-06-01 15:00:46.992 Leftenant[80698:3491613] track[Id 0x1] read 150332 chunk 2017-06-01 15:00:46.993 Leftenant[80698:3491613] STTS table of 1 entries 2017-06-01 15:00:47.008 Leftenant[80698:3491613] CTTS table of 145750 entries 2017-06-01 15:00:47.025 Leftenant[80698:3491613] track[Id 0x1] read 150332 samples length:6270s 2017-06-01 15:00:47.026 Leftenant[80698:3491613] selecting program id=0 2017-06-01 15:00:47.026 Leftenant[80698:3491613] adding track[Id 0x1] video (enable) language undef 2017-06-01 15:00:47.026 Leftenant[80698:3491613] elst box found 2017-06-01 15:00:47.026 Leftenant[80698:3491613] - [0] duration=6270101ms media time=42ms) rate=1.0 2017-06-01 15:00:47.037 Leftenant[80698:3491613] track[Id 0x2] read 150332 chunk 2017-06-01 15:00:47.037 Leftenant[80698:3491613] STTS table of 144875 entries 2017-06-01 15:00:47.056 Leftenant[80698:3491613] track[Id 0x2] read 293913 samples length:6270s 2017-06-01 15:00:47.057 Leftenant[80698:3491613] adding track[Id 0x2] audio (enable) language undef 2017-06-01 15:00:47.057 Leftenant[80698:3491613] using demux module "mp4" 2017-06-01 15:00:47.057 Leftenant[80698:3491613] [VLCMedia] HandleMediaDurationChanged: 6270144 2017-06-01 15:00:47.058 Leftenant[80698:3491613] looking for a subtitle file in /Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/ 2017-06-01 15:00:47.058 Leftenant[80698:3491613] looking for decoder module matching "any": 34 candidates 2017-06-01 15:00:47.059 Leftenant[80698:3491613] trying to decode MPEG-4 Part 10: profile 64, level 29 2017-06-01 15:00:47.059 Leftenant[80698:3491613] new SPS parsed: 0 2017-06-01 15:00:47.112 Leftenant[80698:3491613] new PPS parsed: 0 2017-06-01 15:00:47.116 Leftenant[80698:3491613] Using Video Toolbox to decode 'h264' 2017-06-01 15:00:47.116 Leftenant[80698:3491613] using decoder module "videotoolbox" 2017-06-01 15:00:47.116 Leftenant[80698:3491613] looking for decoder module matching "any": 34 candidates 2017-06-01 15:00:47.117 Leftenant[80698:3491613] using ffmpeg Lavc57.83.100 2017-06-01 15:00:47.117 Leftenant[80698:3491613] option quiet does not exist 2017-06-01 15:00:47.117 Leftenant[80698:3491613] CPU flags: 0x000fd3db 2017-06-01 15:00:47.121 Leftenant[80698:3491613] codec (aac) started 2017-06-01 15:00:47.121 Leftenant[80698:3491613] using decoder module "avcodec" 2017-06-01 15:00:47.122 Leftenant[80698:3491613] looking for meta reader module matching "any": 1 candidates 2017-06-01 15:00:47.123 Leftenant[80698:3491613] using meta reader module "taglib" 2017-06-01 15:00:47.123 Leftenant[80698:3491613] removing module "taglib" 2017-06-01 15:00:47.123 Leftenant[80698:3491613] [VLCMedia] HandleMediaParsedChanged: 1 2017-06-01 15:00:47.124 Leftenant[80698:3491613] [VLCMedia] HandleMediaMetaChanged: artworkURL 2017-06-01 15:00:47.124 Leftenant[80698:3491613] `file:///Users/alex/Library/Developer/CoreSimulator/Devices/0966B472-3F8F-4085-8BB8-5A6575070F30/data/Containers/Data/Application/06BEEED8-1207-4126-9ACD-AB6124169B6A/Library/Caches/Downloads/Videos/video.mp4' successfully opened 2017-06-01 15:00:47.124 Leftenant[80698:3491613] [VLCMedia] HandleMediaStateChanged: 2 2017-06-01 15:00:47.125 Leftenant[80698:3491613] elst (0) gives 0ms (movie)-> 83ms (track) 2017-06-01 15:00:47.125 Leftenant[80698:3491613] track[Id 0x1] using Sync Sample Box (stss) 2017-06-01 15:00:47.126 Leftenant[80698:3490841] mediaPlayerStateChanged: VLCMediaPlayerStatePlaying 2017-06-01 15:00:47.126 Leftenant[80698:3491613] stss gives 2 --> 0 (sample number) 2017-06-01 15:00:47.126 Leftenant[80698:3491613] elst (0) gives 0ms (movie)-> 42ms (track) 2017-06-01 15:00:47.127 Leftenant[80698:3490841] movie state: 2017-06-01 15:00:47.128 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:00:47.128 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:00:47.128 Leftenant[80698:3491613] Buffering 0% 2017-06-01 15:00:47.128 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:00:47.128 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:00:47.129 Leftenant[80698:3490841] state: VLCMediaPlayerStatePlaying 2017-06-01 15:00:47.129 Leftenant[80698:3491621] reusing audio output 2017-06-01 15:00:47.129 Leftenant[80698:3491621] VLC is looking for: 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes 2017-06-01 15:00:47.129 Leftenant[80698:3491613] Buffering 12% 2017-06-01 15:00:47.129 Leftenant[80698:3491621] Output on Default, channel count: 2 2017-06-01 15:00:47.130 Leftenant[80698:3491613] Buffering 25% 2017-06-01 15:00:47.131 Leftenant[80698:3491613] Buffering 37% 2017-06-01 15:00:47.131 Leftenant[80698:3491613] Buffering 50% 2017-06-01 15:00:47.132 Leftenant[80698:3491613] Buffering 62% 2017-06-01 15:00:47.132 Leftenant[80698:3491613] Buffering 75% 2017-06-01 15:00:47.132 Leftenant[80698:3491613] Buffering 87% 2017-06-01 15:00:47.133 Leftenant[80698:3491613] Buffering 100% 2017-06-01 15:00:47.142 Leftenant[80698:3491613] Stream buffering done (1125 ms in 14 ms) 2017-06-01 15:00:47.143 Leftenant[80698:3490841] mediaPlayerStateChanged: VLCMediaPlayerStateBuffering 2017-06-01 15:00:47.144 Leftenant[80698:3490841] movie state: 2017-06-01 15:00:47.144 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:00:47.144 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:00:47.144 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:00:47.144 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:00:47.144 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:00:47.150 Leftenant[80698:3491623] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.157 Leftenant[80698:3491621] not output layout, default to Stereo 2017-06-01 15:00:47.157 Leftenant[80698:3491621] selected 2 physical channels for device output 2017-06-01 15:00:47.158 Leftenant[80698:3491621] VLC will output: Stereo 2017-06-01 15:00:47.158 Leftenant[80698:3491621] Current AU format: [48000.000000][mcpl][9][8][1][8][2][32] 2017-06-01 15:00:47.164 Leftenant[80698:3491623] using text renderer module "freetype" 2017-06-01 15:00:47.164 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.165 Leftenant[80698:3491623] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:00:47.165 Leftenant[80698:3491623] using video converter module "swscale" 2017-06-01 15:00:47.165 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.165 Leftenant[80698:3491623] YUVP to YUVA converter 2017-06-01 15:00:47.166 Leftenant[80698:3491623] using video converter module "yuvp" 2017-06-01 15:00:47.166 Leftenant[80698:3491623] Deinterlacing available 2017-06-01 15:00:47.166 Leftenant[80698:3491623] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:00:47.166 Leftenant[80698:3491623] looking for vout window module matching "any": 0 candidates 2017-06-01 15:00:47.166 Leftenant[80698:3491623] no vout window modules 2017-06-01 15:00:47.167 Leftenant[80698:3491633] Opening vout display wrapper 2017-06-01 15:00:47.167 Leftenant[80698:3491633] looking for vout display module matching "any": 4 candidates 2017-06-01 15:00:47.194 Leftenant[80698:3491633] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:00:47.195 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.195 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.201 Leftenant[80698:3491633] could not init tex converter for CVPY 2017-06-01 15:00:47.201 Leftenant[80698:3491633] deleting display 2017-06-01 15:00:47.202 Leftenant[80698:3491633] no vout display modules matched 2017-06-01 15:00:47.202 Leftenant[80698:3491623] video output creation failed 2017-06-01 15:00:47.202 Leftenant[80698:3491623] removing module "freetype" 2017-06-01 15:00:47.202 Leftenant[80698:3491623] removing module "yuvp" 2017-06-01 15:00:47.203 Leftenant[80698:3491623] removing module "swscale" 2017-06-01 15:00:47.203 Leftenant[80698:3491623] failed to create video output 2017-06-01 15:00:47.203 Leftenant[80698:3491624] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.212 Leftenant[80698:3491621] Current device has a latency of 9999 us 2017-06-01 15:00:47.214 Leftenant[80698:3491624] using text renderer module "freetype" 2017-06-01 15:00:47.214 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.214 Leftenant[80698:3491624] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:00:47.214 Leftenant[80698:3491624] using video converter module "swscale" 2017-06-01 15:00:47.215 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.215 Leftenant[80698:3491624] YUVP to YUVA converter 2017-06-01 15:00:47.215 Leftenant[80698:3491624] using video converter module "yuvp" 2017-06-01 15:00:47.215 Leftenant[80698:3491624] Deinterlacing available 2017-06-01 15:00:47.215 Leftenant[80698:3491624] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:00:47.215 Leftenant[80698:3491624] looking for vout window module matching "any": 0 candidates 2017-06-01 15:00:47.215 Leftenant[80698:349162017-06-01 15:00:47.224216-0400 Leftenant[80698:3491658] [aqme] 254: AQDefaultDevice (1): skipping input stream 0 0 0x0 24] no vout window modules 2017-06-01 15:00:47.233 Leftenant[80698:3491660] Opening vout display wrapper 2017-06-01 15:00:47.233 Leftenant[80698:3491660] looking for vout display module matching "any": 4 candidates 2017-06-01 15:00:47.234 Leftenant[80698:3491660] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:00:47.234 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.234 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.235 Leftenant[80698:3491621] analog AudioUnit output successfully opened for f32l Stereo 2017-06-01 15:00:47.236 Leftenant[80698:3491621] output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes 2017-06-01 15:00:47.236 Leftenant[80698:3491621] looking for audio volume module matching "any": 2 candidates 2017-06-01 15:00:47.236 Leftenant[80698:3491621] using audio volume module "float_mixer" 2017-06-01 15:00:47.236 Leftenant[80698:3491621] input 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes 2017-06-01 15:00:47.236 Leftenant[80698:3491621] looking for audio filter module matching "scaletempo": 11 candidates 2017-06-01 15:00:47.237 Leftenant[80698:3491621] format: 48000 rate, 2 nch, 4 bps, fl32 2017-06-01 15:00:47.237 Leftenant[80698:3491621] params: 30 stride, 0.200 overlap, 14 search 2017-06-01 15:00:47.237 Leftenant[80698:3491621] 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode 2017-06-01 15:00:47.237 Leftenant[80698:3491621] using audio filter module "scaletempo" 2017-06-01 15:00:47.237 Leftenant[80698:3491621] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo 2017-06-01 15:00:47.238 Leftenant[80698:3491621] conversion pipeline complete 2017-06-01 15:00:47.238 Leftenant[80698:3491621] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo 2017-06-01 15:00:47.238 Leftenant[80698:3491621] conversion pipeline complete 2017-06-01 15:00:47.238 Leftenant[80698:3491621] looking for audio resampler module matching "any": 1 candidates 2017-06-01 15:00:47.238 Leftenant[80698:3491621] using audio resampler module "ugly" 2017-06-01 15:00:47.240 Leftenant[80698:3491660] could not init tex converter for CVPY 2017-06-01 15:00:47.240 Leftenant[80698:3491660] deleting display 2017-06-01 15:00:47.241 Leftenant[80698:3491660] no vout display modules matched 2017-06-01 15:00:47.241 Leftenant[80698:3491624] video output creation failed 2017-06-01 15:00:47.241 Leftenant[80698:3491624] removing module "freetype" 2017-06-01 15:00:47.241 Leftenant[80698:3491624] removing module "yuvp" 2017-06-01 15:00:47.241 Leftenant[80698:3491624] removing module "swscale" 2017-06-01 15:00:47.242 Leftenant[80698:3491624] failed to create video output 2017-06-01 15:00:47.242 Leftenant[80698:3491625] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.249 Leftenant[80698:3491625] using text renderer module "freetype" 2017-06-01 15:00:47.249 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.249 Leftenant[80698:3491625] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:00:47.249 Leftenant[80698:3491625] using video converter module "swscale" 2017-06-01 15:00:47.250 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.250 Leftenant[80698:3491625] YUVP to YUVA converter 2017-06-01 15:00:47.250 Leftenant[80698:3491625] using video converter module "yuvp" 2017-06-01 15:00:47.250 Leftenant[80698:3491625] Deinterlacing available 2017-06-01 15:00:47.250 Leftenant[80698:3491625] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:00:47.250 Leftenant[80698:3491625] looking for vout window module matching "any": 0 candidates 2017-06-01 15:00:47.251 Leftenant[80698:3491625] no vout window modules 2017-06-01 15:00:47.251 Leftenant[80698:3491662] Opening vout display wrapper 2017-06-01 15:00:47.251 Leftenant[80698:3491662] looking for vout display module matching "any": 4 candidates 2017-06-01 15:00:47.252 Leftenant[80698:3491662] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:00:47.252 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.252 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.255 Leftenant[80698:3491662] could not init tex converter for CVPY 2017-06-01 15:00:47.256 Leftenant[80698:3491662] deleting display 2017-06-01 15:00:47.256 Leftenant[80698:3491662] no vout display modules matched 2017-06-01 15:00:47.256 Leftenant[80698:3491625] video output creation failed 2017-06-01 15:00:47.256 Leftenant[80698:3491625] removing module "freetype" 2017-06-01 15:00:47.256 Leftenant[80698:3491625] removing module "yuvp" 2017-06-01 15:00:47.257 Leftenant[80698:3491625] removing module "swscale" 2017-06-01 15:00:47.257 Leftenant[80698:3491625] failed to create video output 2017-06-01 15:00:47.260 Leftenant[80698:3491625] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.266 Leftenant[80698:3491625] using text renderer module "freetype" 2017-06-01 15:00:47.266 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.267 Leftenant[80698:3491625] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:00:47.267 Leftenant[80698:3491625] using video converter module "swscale" 2017-06-01 15:00:47.267 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.267 Leftenant[80698:3491625] YUVP to YUVA converter 2017-06-01 15:00:47.267 Leftenant[80698:3491625] using video converter module "yuvp" 2017-06-01 15:00:47.268 Leftenant[80698:3491625] Deinterlacing available 2017-06-01 15:00:47.268 Leftenant[80698:3491625] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:00:47.268 Leftenant[80698:3491625] looking for vout window module matching "any": 0 candidates 2017-06-01 15:00:47.268 Leftenant[80698:3491625] no vout window modules 2017-06-01 15:00:47.268 Leftenant[80698:3491663] Opening vout display wrapper 2017-06-01 15:00:47.269 Leftenant[80698:3491663] looking for vout display module matching "any": 4 candidates 2017-06-01 15:00:47.269 Leftenant[80698:3491663] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:00:47.269 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.269 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.273 Leftenant[80698:3491663] could not init tex converter for CVPY 2017-06-01 15:00:47.273 Leftenant[80698:3491663] deleting display 2017-06-01 15:00:47.274 Leftenant[80698:3491663] no vout display modules matched 2017-06-01 15:00:47.274 Leftenant[80698:3491625] video output creation failed 2017-06-01 15:00:47.274 Leftenant[80698:3491625] removing module "freetype" 2017-06-01 15:00:47.274 Leftenant[80698:3491625] removing module "yuvp" 2017-06-01 15:00:47.274 Leftenant[80698:3491625] removing module "swscale" 2017-06-01 15:00:47.275 Leftenant[80698:3491625] failed to create video output 2017-06-01 15:00:47.275 Leftenant[80698:3491624] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.281 Leftenant[80698:3491624] using text renderer module "freetype" 2017-06-01 15:00:47.282 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.282 Leftenant[80698:3491624] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:00:47.282 Leftenant[80698:3491624] using video converter module "swscale" 2017-06-01 15:00:47.282 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.283 Leftenant[80698:3491624] YUVP to YUVA converter 2017-06-01 15:00:47.283 Leftenant[80698:3491624] using video converter module "yuvp" 2017-06-01 15:00:47.283 Leftenant[80698:3491624] Deinterlacing available 2017-06-01 15:00:47.283 Leftenant[80698:3491624] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:00:47.283 Leftenant[80698:3491624] looking for vout window module matching "any": 0 candidates 2017-06-01 15:00:47.283 Leftenant[80698:3491624] no vout window modules 2017-06-01 15:00:47.284 Leftenant[80698:3491664] Opening vout display wrapper 2017-06-01 15:00:47.284 Leftenant[80698:3491664] looking for vout display module matching "any": 4 candidates 2017-06-01 15:00:47.284 Leftenant[80698:3491664] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:00:47.284 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.285 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:00:47.288 Leftenant[80698:3491664] could not init tex converter for CVPY 2017-06-01 15:00:47.288 Leftenant[80698:3491664] deleting display 2017-06-01 15:00:47.289 Leftenant[80698:3491664] no vout display modules matched 2017-06-01 15:00:47.289 Leftenant[80698:3491624] video output creation failed 2017-06-01 15:00:47.289 Leftenant[80698:3491624] removing module "freetype" 2017-06-01 15:00:47.289 Leftenant[80698:3491624] removing module "yuvp" 2017-06-01 15:00:47.289 Leftenant[80698:3491624] removing module "swscale" 2017-06-01 15:00:47.289 Leftenant[80698:3491624] failed to create video output 2017-06-01 15:00:47.290 Leftenant[80698:3491623] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:00:47.296 Leftenant[80698:3491623] using text renderer module "freetype" 2017-06-01 15:00:47.296 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:00:47.297 Leftenant[80698:3491623] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:00.605 Leftenant[80698:3491623] using video converter module "swscale" 2017-06-01 15:01:00.605 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.605 Leftenant[80698:3491623] YUVP to YUVA converter 2017-06-01 15:01:00.605 Leftenant[80698:3491623] using video converter module "yuvp" 2017-06-01 15:01:00.606 Leftenant[80698:3491623] Deinterlacing available 2017-06-01 15:01:00.606 Leftenant[80698:3491623] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:00.606 Leftenant[80698:3491623] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:00.606 Leftenant[80698:3491623] no vout window modules 2017-06-01 15:01:00.606 Leftenant[80698:3492178] Opening vout display wrapper 2017-06-01 15:01:00.607 Leftenant[80698:3492178] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:00.607 Leftenant[80698:3492178] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:00.607 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.608 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.611 Leftenant[80698:3492178] could not init tex converter for CVPY 2017-06-01 15:01:00.611 Leftenant[80698:3492178] deleting display 2017-06-01 15:01:00.612 Leftenant[80698:3492178] no vout display modules matched 2017-06-01 15:01:00.612 Leftenant[80698:3491623] video output creation failed 2017-06-01 15:01:00.612 Leftenant[80698:3491623] removing module "freetype" 2017-06-01 15:01:00.612 Leftenant[80698:3491623] removing module "yuvp" 2017-06-01 15:01:00.613 Leftenant[80698:3491623] removing module "swscale" 2017-06-01 15:01:00.613 Leftenant[80698:3491623] failed to create video output 2017-06-01 15:01:00.613 Leftenant[80698:3491624] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:00.620 Leftenant[80698:3491624] using text renderer module "freetype" 2017-06-01 15:01:00.620 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.621 Leftenant[80698:3491624] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:00.621 Leftenant[80698:3491624] using video converter module "swscale" 2017-06-01 15:01:00.621 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.621 Leftenant[80698:3491624] YUVP to YUVA converter 2017-06-01 15:01:00.621 Leftenant[80698:3491624] using video converter module "yuvp" 2017-06-01 15:01:00.621 Leftenant[80698:3491624] Deinterlacing available 2017-06-01 15:01:00.622 Leftenant[80698:3491624] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:00.622 Leftenant[80698:3491624] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:00.622 Leftenant[80698:3491624] no vout window modules 2017-06-01 15:01:00.622 Leftenant[80698:3492179] Opening vout display wrapper 2017-06-01 15:01:00.622 Leftenant[80698:3492179] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:00.623 Leftenant[80698:3492179] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:00.624 Leftenant[80698:3490841] movie state: 2017-06-01 15:01:00.624 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:01:00.624 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:01:00.624 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:01:00.624 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:01:00.624 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:01:00.624 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.625 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.628 Leftenant[80698:3492179] could not init tex converter for CVPY 2017-06-01 15:01:00.628 Leftenant[80698:3492179] deleting display 2017-06-01 15:01:00.629 Leftenant[80698:3492179] no vout display modules matched 2017-06-01 15:01:00.629 Leftenant[80698:3491624] video output creation failed 2017-06-01 15:01:00.629 Leftenant[80698:3491624] removing module "freetype" 2017-06-01 15:01:00.629 Leftenant[80698:3491624] removing module "yuvp" 2017-06-01 15:01:00.629 Leftenant[80698:3491624] removing module "swscale" 2017-06-01 15:01:00.630 Leftenant[80698:3491624] failed to create video output 2017-06-01 15:01:00.687 Leftenant[80698:3491623] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:00.695 Leftenant[80698:3491623] using text renderer module "freetype" 2017-06-01 15:01:00.695 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.695 Leftenant[80698:3491623] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:00.696 Leftenant[80698:3491623] using video converter module "swscale" 2017-06-01 15:01:00.696 Leftenant[80698:3491623] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.696 Leftenant[80698:3491623] YUVP to YUVA converter 2017-06-01 15:01:00.696 Leftenant[80698:3491623] using video converter module "yuvp" 2017-06-01 15:01:00.696 Leftenant[80698:3491623] Deinterlacing available 2017-06-01 15:01:00.697 Leftenant[80698:3491623] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:00.697 Leftenant[80698:3491623] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:00.697 Leftenant[80698:3491623] no vout window modules 2017-06-01 15:01:00.703 Leftenant[80698:3492180] Opening vout display wrapper 2017-06-01 15:01:00.703 Leftenant[80698:3492180] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:00.704 Leftenant[80698:3492180] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:00.704 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.704 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.707 Leftenant[80698:3492180] could not init tex converter for CVPY 2017-06-01 15:01:00.708 Leftenant[80698:3492180] deleting display 2017-06-01 15:01:00.708 Leftenant[80698:3492180] no vout display modules matched 2017-06-01 15:01:00.708 Leftenant[80698:3491623] video output creation failed 2017-06-01 15:01:00.708 Leftenant[80698:3491623] removing module "freetype" 2017-06-01 15:01:00.709 Leftenant[80698:3491623] removing module "yuvp" 2017-06-01 15:01:00.709 Leftenant[80698:3491623] removing module "swscale" 2017-06-01 15:01:00.709 Leftenant[80698:3491623] failed to create video output 2017-06-01 15:01:00.713 Leftenant[80698:3491625] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:00.720 Leftenant[80698:3491625] using text renderer module "freetype" 2017-06-01 15:01:00.720 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.721 Leftenant[80698:3491625] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:00.721 Leftenant[80698:3491625] using video converter module "swscale" 2017-06-01 15:01:00.721 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.721 Leftenant[80698:3491625] YUVP to YUVA converter 2017-06-01 15:01:00.721 Leftenant[80698:3491625] using video converter module "yuvp" 2017-06-01 15:01:00.722 Leftenant[80698:3491625] Deinterlacing available 2017-06-01 15:01:00.722 Leftenant[80698:3491625] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:00.722 Leftenant[80698:3491625] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:00.722 Leftenant[80698:3491625] no vout window modules 2017-06-01 15:01:00.722 Leftenant[80698:3492181] Opening vout display wrapper 2017-06-01 15:01:00.775 Leftenant[80698:3492181] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:00.776 Leftenant[80698:3492181] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:00.777 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.777 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:00.781 Leftenant[80698:3492181] could not init tex converter for CVPY 2017-06-01 15:01:00.782 Leftenant[80698:3492181] deleting display 2017-06-01 15:01:00.783 Leftenant[80698:3492181] no vout display modules matched 2017-06-01 15:01:00.785 Leftenant[80698:3491625] video output creation failed 2017-06-01 15:01:00.785 Leftenant[80698:3491625] removing module "freetype" 2017-06-01 15:01:00.785 Leftenant[80698:3491625] removing module "yuvp" 2017-06-01 15:01:00.785 Leftenant[80698:3491625] removing module "swscale" 2017-06-01 15:01:00.785 Leftenant[80698:3491625] failed to create video output 2017-06-01 15:01:00.786 Leftenant[80698:3491624] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:00.795 Leftenant[80698:3491624] using text renderer module "freetype" 2017-06-01 15:01:00.796 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:00.796 Leftenant[80698:3491624] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:00.796 Leftenant[80698:3491624] using video converter module "swscale" 2017-06-01 15:01:00.829 Leftenant[80698:3490841] movie state: 2017-06-01 15:01:02.639754-0400 Leftenant[80698:3491658] [aqme] 254: AQDefaultDevice (123): skipping input stream 0 0 0x0 2017-06-01 15:01:02.639 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:01:02.639 Leftenant[80698:3491624] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:02.639 Leftenant[80698:3491621] buffer too late (-584227 us): dropped 2017-06-01 15:01:02.639 Leftenant[80698:3491613] ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1000 ms) 2017-06-01 15:01:02.640 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:01:02.640 Leftenant[80698:3491624] YUVP to YUVA converter 2017-06-01 15:01:02.640 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:01:02.640 Leftenant[80698:3491621] buffer too late (-563410 us): dropped 2017-06-01 15:01:02.640 Leftenant[80698:3491613] ES_OUT_RESET_PCR called 2017-06-01 15:01:02.640 Leftenant[80698:3491624] using video converter module "yuvp" 2017-06-01 15:01:02.640 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:01:02.641 Leftenant[80698:3491624] Deinterlacing available 2017-06-01 15:01:02.641 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:01:02.641 Leftenant[80698:3491613] Buffering 0% 2017-06-01 15:01:02.641 Leftenant[80698:3491624] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:02.641 Leftenant[80698:3491624] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:02.641 Leftenant[80698:3491613] Buffering 12% 2017-06-01 15:01:02.640 Leftenant[80698:3491621] buffer too late (-542671 us): dropped 2017-06-01 15:01:02.641 Leftenant[80698:3491624] no vout window modules 2017-06-01 15:01:02.641 Leftenant[80698:3491613] Buffering 25% 2017-06-01 15:01:02.642 Leftenant[80698:3492235] Opening vout display wrapper 2017-06-01 15:01:02.642 Leftenant[80698:3491613] Buffering 37% 2017-06-01 15:01:02.642 Leftenant[80698:3490841] movie state: 2017-06-01 15:01:02.642 Leftenant[80698:3492235] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:02.642 Leftenant[80698:3491613] Buffering 50% 2017-06-01 15:01:02.642 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:01:02.642 Leftenant[80698:3491613] Buffering 62% 2017-06-01 15:01:02.642 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:01:02.642 Leftenant[80698:3492235] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:02.643 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:01:02.643 Leftenant[80698:3491613] Buffering 75% 2017-06-01 15:01:02.643 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:01:02.643 Leftenant[80698:3491613] Buffering 87% 2017-06-01 15:01:02.643 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:01:02.643 Leftenant[80698:3491613] Buffering 100% 2017-06-01 15:01:02.643 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.646 Leftenant[80698:3491613] Stream buffering done (1125 ms in 5 ms) 2017-06-01 15:01:02.647 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.647 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.651 Leftenant[80698:3492235] could not init tex converter for CVPY 2017-06-01 15:01:02.652 Leftenant[80698:3492235] deleting display 2017-06-01 15:01:02.652 Leftenant[80698:3492235] no vout display modules matched 2017-06-01 15:01:02.652 Leftenant[80698:3491624] video output creation failed 2017-06-01 15:01:02.653 Leftenant[80698:3491624] removing module "freetype" 2017-06-01 15:01:02.653 Leftenant[80698:3491624] removing module "yuvp" 2017-06-01 15:01:02.653 Leftenant[80698:3490841] mediaPlayerStateChanged: VLCMediaPlayerStateBuffering 2017-06-01 15:01:02.653 Leftenant[80698:3491624] removing module "swscale" 2017-06-01 15:01:02.653 Leftenant[80698:3491624] failed to create video output 2017-06-01 15:01:02.654 Leftenant[80698:3490841] movie state: 2017-06-01 15:01:02.654 Leftenant[80698:3490841] isPlaying: 1 2017-06-01 15:01:02.654 Leftenant[80698:3491620] Restarting decoder session 2017-06-01 15:01:02.654 Leftenant[80698:3490841] willPlay: 1 2017-06-01 15:01:02.654 Leftenant[80698:3490841] isSeekable: 1 2017-06-01 15:01:02.654 Leftenant[80698:3490841] canPause: 1 2017-06-01 15:01:02.656 Leftenant[80698:3491625] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:02.678 Leftenant[80698:3490841] state: VLCMediaPlayerStateBuffering 2017-06-01 15:01:02.685 Leftenant[80698:3491625] using text renderer module "freetype" 2017-06-01 15:01:02.686 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:02.686 Leftenant[80698:3491625] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:02.686 Leftenant[80698:3491625] using video converter module "swscale" 2017-06-01 15:01:02.686 Leftenant[80698:3491625] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:02.687 Leftenant[80698:3491625] YUVP to YUVA converter 2017-06-01 15:01:02.687 Leftenant[80698:3491625] using video converter module "yuvp" 2017-06-01 15:01:02.687 Leftenant[80698:3491625] Deinterlacing available 2017-06-01 15:01:02.687 Leftenant[80698:3491625] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:02.687 Leftenant[80698:3491625] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:02.688 Leftenant[80698:3491625] no vout window modules 2017-06-01 15:01:02.688 Leftenant[80698:3492236] Opening vout display wrapper 2017-06-01 15:01:02.688 Leftenant[80698:3492236] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:02.689 Leftenant[80698:3492236] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:02.689 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.690 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.695 Leftenant[80698:3492236] could not init tex converter for CVPY 2017-06-01 15:01:02.696 Leftenant[80698:3492236] deleting display 2017-06-01 15:01:02.696 Leftenant[80698:3492236] no vout display modules matched 2017-06-01 15:01:02.696 Leftenant[80698:3491625] video output creation failed 2017-06-01 15:01:02.696 Leftenant[80698:3491625] removing module "freetype" 2017-06-01 15:01:02.696 Leftenant[80698:3491625] removing module "yuvp" 2017-06-01 15:01:02.697 Leftenant[80698:3491625] removing module "swscale" 2017-06-01 15:01:02.697 Leftenant[80698:3491625] failed to create video output 2017-06-01 15:01:02.707 Leftenant[80698:3491620] decoding of a frame failed (-8969, 0) 2017-06-01 15:01:02.707 Leftenant[80698:3491620] unknown vt session error (-8969) 2017-06-01 15:01:02.707 Leftenant[80698:3491620] Restarting decoder session 2017-06-01 15:01:02.717 Leftenant[80698:3491620] decoding of a frame failed (-8969, 0) 2017-06-01 15:01:02.717 Leftenant[80698:3491620] decoder failure, Abort. 2017-06-01 15:01:02.717 Leftenant[80698:3491620] Reloading the decoder module 2017-06-01 15:01:02.718 Leftenant[80698:3491620] removing module "videotoolbox" 2017-06-01 15:01:02.719 Leftenant[80698:3491620] looking for decoder module matching "any": 34 candidates 2017-06-01 15:01:02.719 Leftenant[80698:3491620] using ffmpeg Lavc57.83.100 2017-06-01 15:01:02.720 Leftenant[80698:3491620] option quiet does not exist 2017-06-01 15:01:02.720 Leftenant[80698:3491620] CPU flags: 0x000fd3db 2017-06-01 15:01:02.720 Leftenant[80698:3491620] allowing 4 thread(s) for decoding 2017-06-01 15:01:02.721 Leftenant[80698:3491620] codec (h264) started 2017-06-01 15:01:02.721 Leftenant[80698:3491620] using frame thread mode with 4 threads 2017-06-01 15:01:02.721 Leftenant[80698:3491620] using decoder module "avcodec" 2017-06-01 15:01:02.722 Leftenant[80698:3492243] available software decoder output format 0 (yuv420p) 2017-06-01 15:01:02.722 Leftenant[80698:3492243] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:02.729 Leftenant[80698:3492243] using text renderer module "freetype" 2017-06-01 15:01:02.729 Leftenant[80698:3492243] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:02.730 Leftenant[80698:3492243] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) 2017-06-01 15:01:02.730 Leftenant[80698:3492243] using video converter module "swscale" 2017-06-01 15:01:02.730 Leftenant[80698:3492243] looking for video converter module matching "any": 14 candidates 2017-06-01 15:01:02.730 Leftenant[80698:3492243] YUVP to YUVA converter 2017-06-01 15:01:02.731 Leftenant[80698:3492243] using video converter module "yuvp" 2017-06-01 15:01:02.731 Leftenant[80698:3492243] Deinterlacing available 2017-06-01 15:01:02.731 Leftenant[80698:3492243] deinterlace -1, mode blend, is_needed 0 2017-06-01 15:01:02.731 Leftenant[80698:3492243] looking for vout window module matching "any": 0 candidates 2017-06-01 15:01:02.731 Leftenant[80698:3492243] no vout window modules 2017-06-01 15:01:02.732 Leftenant[80698:3492247] Opening vout display wrapper 2017-06-01 15:01:02.732 Leftenant[80698:3492247] looking for vout display module matching "any": 4 candidates 2017-06-01 15:01:02.732 Leftenant[80698:3492247] VoutDisplayEvent 'resize' 320x240 2017-06-01 15:01:02.733 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.733 Leftenant[80698:3490841] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.745 Leftenant[80698:3492247] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.745 Leftenant[80698:3492247] VoutDisplayEvent 'resize' 1920x1080 2017-06-01 15:01:02.745 Leftenant[80698:3492247] using vout display module "ios" 2017-06-01 15:01:02.746 Leftenant[80698:3492247] original format sz 1280x546, of (0,0), vsz 1280x534, 4cc I420, sar 801:800, msk r0x0 g0x0 b0x0 2017-06-01 15:01:02.746 Leftenant[80698:3492247] VoutDisplayEvent 'resize' 1281x534 2017-06-01 15:01:02.746 Leftenant[80698:3492243] removing module "freetype" 2017-06-01 15:01:02.746 Leftenant[80698:3492243] looking for text renderer module matching "freetype": 1 candidates 2017-06-01 15:01:02.753 Leftenant[80698:3492243] using text renderer module "freetype" 2017-06-01 15:01:02.771 Leftenant[80698:3491613] buffer deadlock prevented 2017-06-01 15:01:02.771 Leftenant[80698:3491613] Decoder wait done in 124 ms 2017-06-01 15:01:02.772 Leftenant[80698:3491621] buffer too late (-111512 us): dropped 2017-06-01 15:01:02.772 Leftenant[80698:3491621] buffer too late (-90548 us): dropped 2017-06-01 15:01:02.772 Leftenant[80698:3491621] buffer too late (-69453 us): dropped 2017-06-01 15:01:02.815 Leftenant[80698:3491621] buffer too late (-90771 us): dropped 2017-06-01 15:01:02.815 Leftenant[80698:3491621] buffer too late (-69713 us): dropped 2017-06-01 15:01:02.816 Leftenant[80698:3491621] playback too late (59567): flushing buffers 2017-06-01 15:01:02.817 Leftenant[80698:3491621] underrun of 66872 bytes 2017-06-01 15:01:02.817 Leftenant[80698:3491621] playback too late (60001): up-sampling

Re: TVOS Video display failure

Posted: 08 Jun 2017 22:08
by ssbmaccom
That is an issue I already have reportd as a bug.
Indeed... it runs into a deadlovk, in case of my tvOS App only with 720p or bigger streams, while 576i (PAL) plays fine.

Good news, I was able to build VLCKit today from scratch and it looks like the issue is fixed now.

Re: TVOS Video display failure

Posted: 08 Jun 2017 22:52
by naftaly
That's great news. I'll give it a try.