Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Audio cuts out, Too many packets in the demuxer packet queues #10587

Closed
low-batt opened this issue Aug 29, 2022 · 9 comments
Closed

Audio cuts out, Too many packets in the demuxer packet queues #10587

low-batt opened this issue Aug 29, 2022 · 9 comments

Comments

@low-batt
Copy link
Contributor

Important Information

Provide following Information:

  • mpv version 0.34.1
  • macOS Version 12.5.1.
  • Source of the mpv binary or bundle stolendata
  • If known which version of mpv introduced the problem Unknown
  • Possible screenshot or video of visual glitches

No visual glitches that I noticed.

Reproduction steps

Play the linked video in the sample files section below using the option --hwdec=videotoolbox.

Expected behavior

The video file plays without errors just as it does when hardware decoding is not specified.

Actual behavior

Audio cuts out. Errors are reported.

  • Audio/Video desynchronisation detected!
  • Too many packets in the demuxer packet queues

Using mpv 0.34.1 from stolendata:

Terminal Output:
low-batt@gag Movies$ /Applications/mpv.app/Contents/MacOS/mpv --no-config --log-file=mpv.log --hwdec=videotoolbox issue-3917.mkv 
 (+) Video --vid=1 (*) (h264 3840x2160 60.000fps)
 (+) Audio --aid=1 (*) (aac 2ch 48000Hz)
Using hardware decoding (videotoolbox).
AO: [coreaudio] 48000Hz stereo 2ch floatp
[ffmpeg/video] h264: Increasing reorder buffer to 1
VO: [libmpv] 3840x2160 videotoolbox[nv12]
AV: 00:00:00 / 00:00:02 (42%) A-V:  0.495 Dropped: 37

Audio/Video desynchronisation detected! Possible reasons include too slow
hardware, temporary CPU spikes, broken drivers, and broken files. Audio
position will not match to the video (see A-V status field).

AV: 00:00:01 / 00:00:02 (50%) A-V:  0.592 Dropped: 44
[mkv] Too many packets in the demuxer packet queues:
[mkv]   video/0: 50 packets, 159526928 bytes
[mkv]   audio/1: 0 packets, 0 bytes
AV: 00:00:01 / 00:00:02 (98%) A-V:  0.000 Dropped: 88

Exiting... (End of file)
low-batt@gag Movies$ 

I used mpv-build to locally build mpv from master. Xcode 13.4.1 is installed. The same errors are reported when using that mpv build:

Terminal Output:
low-batt@gag mpv-build (master %=)$ mpv/build/mpv --no-config --hwdec=videotoolbox ~/Movies/issue-3917.mkv 
 (+) Video --vid=1 (*) (h264 3840x2160 60.000fps)
 (+) Audio --aid=1 (*) (aac 2ch 48000Hz)
Using hardware decoding (videotoolbox).
AO: [coreaudio] 48000Hz stereo 2ch floatp
[ffmpeg/video] h264: Increasing reorder buffer to 1
VO: [libmpv] 3840x2160 videotoolbox[nv12]
AV: 00:00:00 / 00:00:02 (43%) A-V:  0.497 Dropped: 35

Audio/Video desynchronisation detected! Possible reasons include too slow
hardware, temporary CPU spikes, broken drivers, and broken files. Audio
position will not match to the video (see A-V status field).

AV: 00:00:01 / 00:00:02 (51%) A-V:  0.589 Dropped: 42
[mkv] Too many packets in the demuxer packet queues:
[mkv]   video/0: 49 packets, 158306912 bytes
[mkv]   audio/1: 0 packets, 0 bytes
AV: 00:00:01 / 00:00:02 (98%) A-V:  0.000 Dropped: 84

Exiting... (End of file)
low-batt@gag mpv-build (master %=)$ 

I tried decoding the file using ffmpeg with hardware decoding enabled and no errors were reported:

Terminal Output:
low-batt@gag ffmpeg (master $%=)$ ./ffmpeg -loglevel verbose -hwaccel videotoolbox -i ~/Movies/issue-3917.mkv -f null -
ffmpeg version N-107541-g81ebf40efa Copyright (c) 2000-2022 the FFmpeg developers
  built with Apple clang version 13.1.6 (clang-1316.0.21.2.5)
  configuration: 
  libavutil      57. 30.100 / 57. 30.100
  libavcodec     59. 39.100 / 59. 39.100
  libavformat    59. 29.100 / 59. 29.100
  libavdevice    59.  8.101 / 59.  8.101
  libavfilter     8. 46.100 /  8. 46.100
  libswscale      6.  8.101 /  6.  8.101
  libswresample   4.  8.100 /  4.  8.100
[h264 @ 0x121805730] Reinit context to 3840x2160, pix_fmt: yuv420p
[h264 @ 0x121805730] Increasing reorder buffer to 1
Input #0, matroska,webm, from '/Users/low-batt/Movies/issue-3917.mkv':
  Metadata:
    ENCODER         : Lavf59.16.100
  Duration: 00:00:02.02, bitrate: 1576973 kb/s
  Stream #0:0: Video: h264 (High 4:4:4 Predictive), 1 reference frame, yuv420p(tv, bt709, progressive, left), 3840x2160 [SAR 1:1 DAR 16:9], 60 fps, 60 tbr, 1k tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
  Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp (default)
    Metadata:
      DURATION        : 00:00:01.998000000
Stream mapping:
  Stream #0:0 -> #0:0 (h264 (native) -> wrapped_avframe (native))
  Stream #0:1 -> #0:1 (aac (native) -> pcm_s16le (native))
Press [q] to stop, [?] for help
[h264 @ 0x120f08770] Reinit context to 3840x2160, pix_fmt: videotoolbox_vld
[graph_1_in_0_1 @ 0x102704af0] tb:1/48000 samplefmt:fltp samplerate:48000 chlayout:stereo
[format_out_0_1 @ 0x102704f50] auto-inserting filter 'auto_aresample_0' between the filter 'Parsed_anull_0' and the filter 'format_out_0_1'
[auto_aresample_0 @ 0x102705520] ch:2 chl:stereo fmt:fltp r:48000Hz -> ch:2 chl:stereo fmt:s16 r:48000Hz
[graph 0 input from stream 0:0 @ 0x120f78fd0] w:3840 h:2160 pixfmt:nv12 tb:1/1000 fr:60/1 sar:1/1
Output #0, null, to 'pipe:':
  Metadata:
    encoder         : Lavf59.29.100
  Stream #0:0: Video: wrapped_avframe, 1 reference frame, nv12(tv, bt709, progressive, left), 3840x2160 (0x0) [SAR 1:1 DAR 16:9], q=2-31, 200 kb/s, 60 fps, 60 tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
      encoder         : Lavc59.39.100 wrapped_avframe
  Stream #0:1: Audio: pcm_s16le, 48000 Hz, stereo, s16, 1536 kb/s (default)
    Metadata:
      DURATION        : 00:00:01.998000000
      encoder         : Lavc59.39.100 pcm_s16le
No more output streams to write to, finishing.:01.95 bitrate=N/A speed=0.566x    
All streams finished for output file #0
Terminating muxer thread 0
frame=  121 fps= 34 q=-0.0 Lsize=N/A time=00:00:02.00 bitrate=N/A speed=0.562x    
video:57kB audio:380kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
Input file #0 (/Users/low-batt/Movies/issue-3917.mkv):
  Input stream #0:0 (video): 121 packets read (397353064 bytes); 121 frames decoded; 
  Input stream #0:1 (audio): 95 packets read (40747 bytes); 95 frames decoded (97280 samples); 
  Total: 216 packets (397393811 bytes) demuxed
Output file #0 (pipe:):
  Output stream #0:0 (video): 121 frames encoded; 121 packets muxed (58080 bytes); 
  Output stream #0:1 (audio): 95 frames encoded (97280 samples); 95 packets muxed (389120 bytes); 
  Total: 216 packets (447200 bytes) muxed
[AVIOContext @ 0x121805120] Statistics: 397397332 bytes read, 0 seeks
low-batt@gag ffmpeg (master $%=)$ 

In searching for existing mpv issues I found issue #8986 which seemed like it might be the same problem. That issue was closed due to lack of information.

This issue originates from IINA issue iina/iina#3917.

Log file

mpv Log:
[   0.003][v][cplayer] mpv 0.34.1 Copyright © 2000-2021 mpv/MPlayer/mplayer2 projects
[   0.003][v][cplayer]  built on Sat Jan  8 16:50:23 CET 2022
[   0.003][v][cplayer] FFmpeg library versions:
[   0.003][v][cplayer]    libavutil       56.70.100
[   0.003][v][cplayer]    libavcodec      58.134.100
[   0.003][v][cplayer]    libavformat     58.76.100
[   0.003][v][cplayer]    libswscale      5.9.100
[   0.003][v][cplayer]    libavfilter     7.110.100
[   0.003][v][cplayer]    libswresample   3.9.100
[   0.003][v][cplayer] FFmpeg version: 4.4.1
[   0.003][v][cplayer] 
[   0.003][v][cplayer] Configuration: waf configure --prefix=/Users/djinn/ghislainestrialwasalie/build-0.34.1 --confloaddir=/usr/local/etc/mpv --enable-manpage-build --enable-html-build --disable-android --disable-tvos --disable-egl-android --disable-macos-media-player --lua=51deb
[   0.003][v][cplayer] List of enabled features: 51deb asm bsd-fstatfs build-date cocoa coreaudio cplayer cplugins debug-build ffmpeg ffmpeg-aviocontext-bytes-read gl gl-cocoa glob glob-posix gpl iconv javascript lcms2 libarchive libass libavdevice libdl libm lua macos-10-11-features macos-10-12-2-features macos-10-14-features macos-cocoa-cb macos-touchbar optimize osx-thread-name posix posix-or-mingw pthreads rubberband stdatomic swift uchardet vector videotoolbox-gl videotoolbox-hwaccel zimg zlib
[   0.003][v][cplayer] Command line options: '--no-config' '--log-file=mpv.log' '--hwdec=videotoolbox' 'issue-3917.mkv'
[   0.003][v][cplayer] mpv 0.34.1 Copyright © 2000-2021 mpv/MPlayer/mplayer2 projects
[   0.003][v][cplayer]  built on Sat Jan  8 16:50:23 CET 2022
[   0.003][v][cplayer] FFmpeg library versions:
[   0.003][v][cplayer]    libavutil       56.70.100
[   0.003][v][cplayer]    libavcodec      58.134.100
[   0.003][v][cplayer]    libavformat     58.76.100
[   0.003][v][cplayer]    libswscale      5.9.100
[   0.003][v][cplayer]    libavfilter     7.110.100
[   0.003][v][cplayer]    libswresample   3.9.100
[   0.003][v][cplayer] FFmpeg version: 4.4.1
[   0.003][v][cplayer] 
[   0.003][v][cplayer] Configuration: waf configure --prefix=/Users/djinn/ghislainestrialwasalie/build-0.34.1 --confloaddir=/usr/local/etc/mpv --enable-manpage-build --enable-html-build --disable-android --disable-tvos --disable-egl-android --disable-macos-media-player --lua=51deb
[   0.003][v][cplayer] List of enabled features: 51deb asm bsd-fstatfs build-date cocoa coreaudio cplayer cplugins debug-build ffmpeg ffmpeg-aviocontext-bytes-read gl gl-cocoa glob glob-posix gpl iconv javascript lcms2 libarchive libass libavdevice libdl libm lua macos-10-11-features macos-10-12-2-features macos-10-14-features macos-cocoa-cb macos-touchbar optimize osx-thread-name posix posix-or-mingw pthreads rubberband stdatomic swift uchardet vector videotoolbox-gl videotoolbox-hwaccel zimg zlib
[   0.003][d][global] config path: '' -> '-'
[   0.003][d][global] user path: '~~home/' -> ''
[   0.003][d][global] user path: '~~old_home/' -> ''
[   0.003][v][cplayer] Setting option 'config' = 'no' (flags = 8)
[   0.003][v][cplayer] Setting option 'log-file' = 'mpv.log' (flags = 8)
[   0.003][v][cplayer] Setting option 'hwdec' = 'videotoolbox' (flags = 8)
[   0.003][d][global] user path: 'mpv.log' -> 'mpv.log'
[   0.004][d][osc] Loading lua script @osc.lua...
[   0.004][d][ytdl_hook] Loading lua script @ytdl_hook.lua...
[   0.004][d][stats] Loading lua script @stats.lua...
[   0.004][d][console] Loading lua script @console.lua...
[   0.004][d][auto_profiles] Loading lua script @auto_profiles.lua...
[   0.004][d][ytdl_hook] loading mp.defaults
[   0.004][d][osc] loading mp.defaults
[   0.004][d][stats] loading mp.defaults
[   0.004][d][console] loading mp.defaults
[   0.004][d][auto_profiles] loading mp.defaults
[   0.005][d][ytdl_hook] loading @ytdl_hook.lua
[   0.005][d][auto_profiles] loading @auto_profiles.lua
[   0.005][d][stats] loading @stats.lua
[   0.005][d][osc] loading @osc.lua
[   0.005][d][console] loading @console.lua
[   0.006][d][ytdl_hook] reading options for ytdl_hook 
[   0.006][d][ytdl_hook] script-opts/ytdl_hook.conf not found. 
[   0.006][d][ytdl_hook] lua-settings/ytdl_hook.conf not found. 
[   0.006][d][stats] reading options for stats 
[   0.006][d][stats] script-opts/stats.conf not found. 
[   0.006][d][stats] lua-settings/stats.conf not found. 
[   0.007][d][osc] reading options for osc 
[   0.007][d][osc] script-opts/osc.conf not found. 
[   0.007][d][osc] lua-settings/osc.conf not found. 
[   0.027][v][osx/cocoacb] Created CGL pixel format with attributes: kCGLPFAOpenGLProfile, kCGLOGLPVersion_3_2_Core, kCGLPFAAccelerated, kCGLPFADoubleBuffer, kCGLPFAColorSize, 64, kCGLPFAColorFloat, kCGLPFABackingStore, kCGLPFAAllowOfflineRenderers, kCGLPFASupportsAutomaticGraphicsSwitching, 0
[   0.030][v][libmpv_render] GL_VERSION='4.1 Metal - 76.3'
[   0.030][v][libmpv_render] Detected desktop OpenGL 4.1.
[   0.030][v][libmpv_render] GL_VENDOR='Apple'
[   0.030][v][libmpv_render] GL_RENDERER='Apple M1 Max'
[   0.030][v][libmpv_render] GL_SHADING_LANGUAGE_VERSION='4.10'
[   0.030][d][libmpv_render] Combined OpenGL extensions string:
[   0.030][d][libmpv_render]  GL_ARB_blend_func_extended GL_ARB_draw_buffers_blend GL_ARB_draw_indirect GL_ARB_ES2_compatibility GL_ARB_explicit_attrib_location GL_ARB_gpu_shader_fp64 GL_ARB_gpu_shader5 GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_occlusion_query2 GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_subroutine GL_ARB_shading_language_include GL_ARB_tessellation_shader GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_cube_map_array GL_ARB_texture_gather GL_ARB_texture_query_lod GL_ARB_texture_rgb10_a2ui GL_ARB_texture_storage GL_ARB_texture_swizzle GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ARB_vertex_attrib_64bit GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_EXT_debug_label GL_EXT_debug_marker GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_texture_compression_s3tc GL_EXT_texture_filter_anisotropic GL_EXT_texture_sRGB_decode GL_APPLE_client_storage GL_APPLE_container_object_shareable GL_APPLE_flush_render GL_APPLE_rgb_422 GL_APPLE_row_bytes GL_APPLE_texture_range GL_NV_texture_barrier
[   0.030][v][libmpv_render] Loaded extension GL_APPLE_rgb_422.
[   0.030][v][libmpv_render] GL_*_swap_control extension missing.
[   0.031][d][libmpv_render] Texture formats:
[   0.031][d][libmpv_render]   NAME       COMP*TYPE SIZE           DEPTH PER COMP.
[   0.031][d][libmpv_render]   r8         1*unorm   1B    LF CR ST {8}
[   0.031][d][libmpv_render]   rg8        2*unorm   2B    LF CR ST {8 8}
[   0.031][d][libmpv_render]   rgb8       3*unorm   3B    LF CR ST {8 8 8}
[   0.031][d][libmpv_render]   rgba8      4*unorm   4B    LF CR ST {8 8 8 8}
[   0.031][d][libmpv_render]   r16        1*unorm   2B    LF CR ST {16}
[   0.031][d][libmpv_render]   rg16       2*unorm   4B    LF CR ST {16 16}
[   0.031][d][libmpv_render]   rgb16      3*unorm   6B    LF CR ST {16 16 16}
[   0.031][d][libmpv_render]   rgba16     4*unorm   8B    LF CR ST {16 16 16 16}
[   0.031][d][libmpv_render]   r8ui       1*uint    1B       CR ST {8}
[   0.031][d][libmpv_render]   rg8ui      2*uint    2B       CR ST {8 8}
[   0.031][d][libmpv_render]   rgb8ui     3*uint    3B          ST {8 8 8}
[   0.031][d][libmpv_render]   rgba8ui    4*uint    4B       CR ST {8 8 8 8}
[   0.031][d][libmpv_render]   r16ui      1*uint    2B       CR ST {16}
[   0.031][d][libmpv_render]   rg16ui     2*uint    4B       CR ST {16 16}
[   0.031][d][libmpv_render]   rgb16ui    3*uint    6B          ST {16 16 16}
[   0.031][d][libmpv_render]   rgba16ui   4*uint    8B       CR ST {16 16 16 16}
[   0.031][d][libmpv_render]   r16f       1*float   4B    LF CR ST {32/16}
[   0.031][d][libmpv_render]   rg16f      2*float   8B    LF CR ST {32/16 32/16}
[   0.031][d][libmpv_render]   rgb16f     3*float  12B    LF CR ST {32/16 32/16 32/16}
[   0.031][d][libmpv_render]   rgba16f    4*float  16B    LF CR ST {32/16 32/16 32/16 32/16}
[   0.031][d][libmpv_render]   r32f       1*float   4B    LF CR ST {32}
[   0.031][d][libmpv_render]   rg32f      2*float   8B    LF CR ST {32 32}
[   0.031][d][libmpv_render]   rgb32f     3*float  12B    LF CR ST {32 32 32}
[   0.031][d][libmpv_render]   rgba32f    4*float  16B    LF CR ST {32 32 32 32}
[   0.031][d][libmpv_render]   rgb10_a2   4*unorm   4B    LF CR ST {0/10 0/10 0/10 0/2}
[   0.031][d][libmpv_render]   rgba12     4*unorm   8B    LF CR ST {16/12 16/12 16/12 16/12}
[   0.031][d][libmpv_render]   rgb10      3*unorm   6B    LF CR ST {16/10 16/10 16/10}
[   0.031][d][libmpv_render]   rgb565     3*unorm   2B    LF    ST {0/8 0/8 0/8}
[   0.031][d][libmpv_render]   appleyp    0*unorm   2B    LF    ST {}
[   0.031][d][libmpv_render]  LA = LUMINANCE_ALPHA hack format
[   0.031][d][libmpv_render]  LF = linear filterable
[   0.031][d][libmpv_render]  CR = can be used for render targets
[   0.031][d][libmpv_render]  ST = can be used for storable images
[   0.031][d][libmpv_render] Image formats:
[   0.031][d][libmpv_render]   yuv444p => 3 planes 1x1 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv420p => 3 planes 2x2 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   gray => 1 planes 1x1 8/0 [r8] (r) [unorm]
[   0.031][d][libmpv_render]   gray16 => 1 planes 1x1 16/0 [r16] (r) [unorm]
[   0.031][d][libmpv_render]   uyvy422 => 1 planes 1x1 0/0 [appleyp] (brg) [unknown]
[   0.031][d][libmpv_render]   nv12 => 2 planes 2x2 8/0 [r8/rg8] (r/gb) [unorm]
[   0.031][d][libmpv_render]   p010 => 2 planes 2x2 16/6 [r16/rg16] (r/gb) [unorm]
[   0.031][d][libmpv_render]   argb => 1 planes 1x1 8/0 [rgba8] (argb) [unorm]
[   0.031][d][libmpv_render]   bgra => 1 planes 1x1 8/0 [rgba8] (bgra) [unorm]
[   0.031][d][libmpv_render]   abgr => 1 planes 1x1 8/0 [rgba8] (abgr) [unorm]
[   0.031][d][libmpv_render]   rgba => 1 planes 1x1 8/0 [rgba8] (rgba) [unorm]
[   0.031][d][libmpv_render]   bgr24 => 1 planes 1x1 8/0 [rgb8] (bgr) [unorm]
[   0.031][d][libmpv_render]   rgb24 => 1 planes 1x1 8/0 [rgb8] (rgb) [unorm]
[   0.031][d][libmpv_render]   0rgb => 1 planes 1x1 8/0 [rgba8] (_rgb) [unorm]
[   0.031][d][libmpv_render]   bgr0 => 1 planes 1x1 8/0 [rgba8] (bgr) [unorm]
[   0.031][d][libmpv_render]   0bgr => 1 planes 1x1 8/0 [rgba8] (_bgr) [unorm]
[   0.031][d][libmpv_render]   rgb0 => 1 planes 1x1 8/0 [rgba8] (rgb) [unorm]
[   0.031][d][libmpv_render]   rgba64 => 1 planes 1x1 16/0 [rgba16] (rgba) [unorm]
[   0.031][d][libmpv_render]   rgb565 => 1 planes 1x1 0/0 [rgb565] (rgb) [unknown]
[   0.031][d][libmpv_render]   pal8
[   0.031][d][libmpv_render]   vdpau
[   0.031][d][libmpv_render]   d3d11
[   0.031][d][libmpv_render]   dxva2_vld
[   0.031][d][libmpv_render]   mmal
[   0.031][d][libmpv_render]   mediacodec
[   0.031][d][libmpv_render]   drm_prime
[   0.031][d][libmpv_render]   cuda
[   0.031][d][libmpv_render]   yap8 => 2 planes 1x1 8/0 [r8/r8] (r/a) [unorm]
[   0.031][d][libmpv_render]   yap16 => 2 planes 1x1 16/0 [r16/r16] (r/a) [unorm]
[   0.031][d][libmpv_render]   grayaf32 => 2 planes 1x1 32/0 [r16f/r16f] (r/a) [float]
[   0.031][d][libmpv_render]   yuv444pf => 3 planes 1x1 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva444pf => 4 planes 1x1 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   yuv420pf => 3 planes 2x2 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva420pf => 4 planes 2x2 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   yuv422pf => 3 planes 2x1 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva422pf => 4 planes 2x1 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   yuv440pf => 3 planes 1x2 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva440pf => 4 planes 1x2 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   yuv410pf => 3 planes 4x4 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva410pf => 4 planes 4x4 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   yuv411pf => 3 planes 4x1 32/0 [r16f/r16f/r16f] (r/g/b) [float]
[   0.031][d][libmpv_render]   yuva411pf => 4 planes 4x1 32/0 [r16f/r16f/r16f/r16f] (r/g/b/a) [float]
[   0.031][d][libmpv_render]   rgb30 => 1 planes 1x1 10/0 [rgb10_a2] (bgr) [unknown]
[   0.031][d][libmpv_render]   y1 => 1 planes 1x1 8/-7 [r8] (r) [unorm]
[   0.031][d][libmpv_render]   gbrp1 => 3 planes 1x1 8/-7 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp2 => 3 planes 1x1 8/-6 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp3 => 3 planes 1x1 8/-5 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp4 => 3 planes 1x1 8/-4 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp5 => 3 planes 1x1 8/-3 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp6 => 3 planes 1x1 8/-2 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   vdpau_output
[   0.031][d][libmpv_render]   vaapi
[   0.031][d][libmpv_render]   videotoolbox
[   0.031][d][libmpv_render]   yuyv422
[   0.031][d][libmpv_render]   yuv422p => 3 planes 2x1 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv410p => 3 planes 4x4 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv411p => 3 planes 4x1 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   monow
[   0.031][d][libmpv_render]   monob
[   0.031][d][libmpv_render]   yuvj422p => 3 planes 2x1 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   uyyvyy411
[   0.031][d][libmpv_render]   bgr8
[   0.031][d][libmpv_render]   bgr4
[   0.031][d][libmpv_render]   bgr4_byte
[   0.031][d][libmpv_render]   rgb8
[   0.031][d][libmpv_render]   rgb4
[   0.031][d][libmpv_render]   rgb4_byte
[   0.031][d][libmpv_render]   nv21 => 2 planes 2x2 8/0 [r8/rg8] (r/bg) [unorm]
[   0.031][d][libmpv_render]   gray16be
[   0.031][d][libmpv_render]   yuv440p => 3 planes 1x2 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuvj440p => 3 planes 1x2 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuva420p => 4 planes 2x2 8/0 [r8/r8/r8/r8] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   rgb48be
[   0.031][d][libmpv_render]   rgb48 => 1 planes 1x1 16/0 [rgb16] (rgb) [unorm]
[   0.031][d][libmpv_render]   rgb565be
[   0.031][d][libmpv_render]   rgb555be
[   0.031][d][libmpv_render]   rgb555
[   0.031][d][libmpv_render]   bgr565be
[   0.031][d][libmpv_render]   bgr565
[   0.031][d][libmpv_render]   bgr555be
[   0.031][d][libmpv_render]   bgr555
[   0.031][d][libmpv_render]   vaapi_moco
[   0.031][d][libmpv_render]   vaapi_idct
[   0.031][d][libmpv_render]   yuv420p16 => 3 planes 2x2 16/0 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv420p16be
[   0.031][d][libmpv_render]   yuv422p16 => 3 planes 2x1 16/0 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv422p16be
[   0.031][d][libmpv_render]   yuv444p16 => 3 planes 1x1 16/0 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv444p16be
[   0.031][d][libmpv_render]   rgb444
[   0.031][d][libmpv_render]   rgb444be
[   0.031][d][libmpv_render]   bgr444
[   0.031][d][libmpv_render]   bgr444be
[   0.031][d][libmpv_render]   ya8 => 1 planes 1x1 8/0 [rg8] (ra) [unorm]
[   0.031][d][libmpv_render]   bgr48be
[   0.031][d][libmpv_render]   bgr48 => 1 planes 1x1 16/0 [rgb16] (bgr) [unorm]
[   0.031][d][libmpv_render]   yuv420p9be
[   0.031][d][libmpv_render]   yuv420p9 => 3 planes 2x2 16/-7 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv420p10be
[   0.031][d][libmpv_render]   yuv420p10 => 3 planes 2x2 16/-6 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv422p10be
[   0.031][d][libmpv_render]   yuv422p10 => 3 planes 2x1 16/-6 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv444p9be
[   0.031][d][libmpv_render]   yuv444p9 => 3 planes 1x1 16/-7 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv444p10be
[   0.031][d][libmpv_render]   yuv444p10 => 3 planes 1x1 16/-6 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv422p9be
[   0.031][d][libmpv_render]   yuv422p9 => 3 planes 2x1 16/-7 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   gbrp => 3 planes 1x1 8/0 [r8/r8/r8] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp9be
[   0.031][d][libmpv_render]   gbrp9 => 3 planes 1x1 16/-7 [r16/r16/r16] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp10be
[   0.031][d][libmpv_render]   gbrp10 => 3 planes 1x1 16/-6 [r16/r16/r16] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp16be
[   0.031][d][libmpv_render]   gbrp16 => 3 planes 1x1 16/0 [r16/r16/r16] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   yuva422p => 4 planes 2x1 8/0 [r8/r8/r8/r8] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva444p => 4 planes 1x1 8/0 [r8/r8/r8/r8] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva420p9be
[   0.031][d][libmpv_render]   yuva420p9 => 4 planes 2x2 16/-7 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva422p9be
[   0.031][d][libmpv_render]   yuva422p9 => 4 planes 2x1 16/-7 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva444p9be
[   0.031][d][libmpv_render]   yuva444p9 => 4 planes 1x1 16/-7 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva420p10be
[   0.031][d][libmpv_render]   yuva420p10 => 4 planes 2x2 16/-6 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva422p10be
[   0.031][d][libmpv_render]   yuva422p10 => 4 planes 2x1 16/-6 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva444p10be
[   0.031][d][libmpv_render]   yuva444p10 => 4 planes 1x1 16/-6 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva420p16be
[   0.031][d][libmpv_render]   yuva420p16 => 4 planes 2x2 16/0 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva422p16be
[   0.031][d][libmpv_render]   yuva422p16 => 4 planes 2x1 16/0 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva444p16be
[   0.031][d][libmpv_render]   yuva444p16 => 4 planes 1x1 16/0 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   xyz12 => 1 planes 1x1 16/4 [rgb16] (rgb) [unorm]
[   0.031][d][libmpv_render]   xyz12be
[   0.031][d][libmpv_render]   nv16 => 2 planes 2x1 8/0 [r8/rg8] (r/gb) [unorm]
[   0.031][d][libmpv_render]   nv20 => 2 planes 2x1 16/-6 [r16/rg16] (r/gb) [unorm]
[   0.031][d][libmpv_render]   nv20be
[   0.031][d][libmpv_render]   rgba64be
[   0.031][d][libmpv_render]   bgra64be
[   0.031][d][libmpv_render]   bgra64 => 1 planes 1x1 16/0 [rgba16] (bgra) [unorm]
[   0.031][d][libmpv_render]   yvyu422
[   0.031][d][libmpv_render]   ya16be
[   0.031][d][libmpv_render]   ya16 => 1 planes 1x1 16/0 [rg16] (ra) [unorm]
[   0.031][d][libmpv_render]   gbrap => 4 planes 1x1 8/0 [r8/r8/r8/r8] (g/b/r/a) [unorm]
[   0.031][d][libmpv_render]   gbrap16be
[   0.031][d][libmpv_render]   gbrap16 => 4 planes 1x1 16/0 [r16/r16/r16/r16] (g/b/r/a) [unorm]
[   0.031][d][libmpv_render]   qsv
[   0.031][d][libmpv_render]   d3d11va_vld
[   0.031][d][libmpv_render]   yuv420p12be
[   0.031][d][libmpv_render]   yuv420p12 => 3 planes 2x2 16/-4 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv420p14be
[   0.031][d][libmpv_render]   yuv420p14 => 3 planes 2x2 16/-2 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv422p12be
[   0.031][d][libmpv_render]   yuv422p12 => 3 planes 2x1 16/-4 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv422p14be
[   0.031][d][libmpv_render]   yuv422p14 => 3 planes 2x1 16/-2 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv444p12be
[   0.031][d][libmpv_render]   yuv444p12 => 3 planes 1x1 16/-4 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv444p14be
[   0.031][d][libmpv_render]   yuv444p14 => 3 planes 1x1 16/-2 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   gbrp12be
[   0.031][d][libmpv_render]   gbrp12 => 3 planes 1x1 16/-4 [r16/r16/r16] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   gbrp14be
[   0.031][d][libmpv_render]   gbrp14 => 3 planes 1x1 16/-2 [r16/r16/r16] (g/b/r) [unorm]
[   0.031][d][libmpv_render]   yuvj411p => 3 planes 4x1 8/0 [r8/r8/r8] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   bayer_bggr8
[   0.031][d][libmpv_render]   bayer_rggb8
[   0.031][d][libmpv_render]   bayer_gbrg8
[   0.031][d][libmpv_render]   bayer_grbg8
[   0.031][d][libmpv_render]   bayer_bggr16
[   0.031][d][libmpv_render]   bayer_bggr16be
[   0.031][d][libmpv_render]   bayer_rggb16
[   0.031][d][libmpv_render]   bayer_rggb16be
[   0.031][d][libmpv_render]   bayer_gbrg16
[   0.031][d][libmpv_render]   bayer_gbrg16be
[   0.031][d][libmpv_render]   bayer_grbg16
[   0.031][d][libmpv_render]   bayer_grbg16be
[   0.031][d][libmpv_render]   xvmc
[   0.031][d][libmpv_render]   yuv440p10 => 3 planes 1x2 16/-6 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv440p10be
[   0.031][d][libmpv_render]   yuv440p12 => 3 planes 1x2 16/-4 [r16/r16/r16] (r/g/b) [unorm]
[   0.031][d][libmpv_render]   yuv440p12be
[   0.031][d][libmpv_render]   ayuv64 => 1 planes 1x1 16/0 [rgba16] (argb) [unorm]
[   0.031][d][libmpv_render]   ayuv64be
[   0.031][d][libmpv_render]   p010be
[   0.031][d][libmpv_render]   gbrap12be
[   0.031][d][libmpv_render]   gbrap12 => 4 planes 1x1 16/-4 [r16/r16/r16/r16] (g/b/r/a) [unorm]
[   0.031][d][libmpv_render]   gbrap10be
[   0.031][d][libmpv_render]   gbrap10 => 4 planes 1x1 16/-6 [r16/r16/r16/r16] (g/b/r/a) [unorm]
[   0.031][d][libmpv_render]   gray12be
[   0.031][d][libmpv_render]   gray12 => 1 planes 1x1 16/-4 [r16] (r) [unorm]
[   0.031][d][libmpv_render]   gray10be
[   0.031][d][libmpv_render]   gray10 => 1 planes 1x1 16/-6 [r16] (r) [unorm]
[   0.031][d][libmpv_render]   p016 => 2 planes 2x2 16/0 [r16/rg16] (r/gb) [unorm]
[   0.031][d][libmpv_render]   p016be
[   0.031][d][libmpv_render]   gray9be
[   0.031][d][libmpv_render]   gray9 => 1 planes 1x1 16/-7 [r16] (r) [unorm]
[   0.031][d][libmpv_render]   gbrpf32be
[   0.031][d][libmpv_render]   gbrpf32 => 3 planes 1x1 32/0 [r16f/r16f/r16f] (g/b/r) [float]
[   0.031][d][libmpv_render]   gbrapf32be
[   0.031][d][libmpv_render]   gbrapf32 => 4 planes 1x1 32/0 [r16f/r16f/r16f/r16f] (g/b/r/a) [float]
[   0.031][d][libmpv_render]   opencl
[   0.031][d][libmpv_render]   gray14be
[   0.031][d][libmpv_render]   gray14 => 1 planes 1x1 16/-2 [r16] (r) [unorm]
[   0.031][d][libmpv_render]   grayf32be
[   0.031][d][libmpv_render]   grayf32 => 1 planes 1x1 32/0 [r16f] (r) [float]
[   0.031][d][libmpv_render]   yuva422p12be
[   0.031][d][libmpv_render]   yuva422p12 => 4 planes 2x1 16/-4 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   yuva444p12be
[   0.031][d][libmpv_render]   yuva444p12 => 4 planes 1x1 16/-4 [r16/r16/r16/r16] (r/g/b/a) [unorm]
[   0.031][d][libmpv_render]   nv24 => 2 planes 1x1 8/0 [r8/rg8] (r/gb) [unorm]
[   0.031][d][libmpv_render]   nv42 => 2 planes 1x1 8/0 [r8/rg8] (r/bg) [unorm]
[   0.031][d][libmpv_render]   vulkan
[   0.031][d][libmpv_render]   y210be
[   0.031][d][libmpv_render]   y210
[   0.031][d][libmpv_render]   x2rgb10be
[   0.031][v][libmpv_render] Testing FBO format rgba16f
[   0.031][d][libmpv_render] Resizing texture: 16x16
[   0.032][v][libmpv_render] Using FBO format rgba16f.
[   0.032][v][libmpv_render] Disabling HDR peak computation (one or more of the following is not supported: compute shaders=0, SSBO=0).
[   0.032][v][libmpv_render] No advanced processing required. Enabling dumb mode.
[   0.032][v][libmpv_render] Loading hwdec driver 'videotoolbox'
[   0.032][v][cplayer] Waiting for scripts...
[   0.033][d][console] reading options for console 
[   0.033][d][console] script-opts/console.conf not found. 
[   0.033][d][console] lua-settings/console.conf not found. 
[   0.033][d][auto_profiles] Exiting...
[   0.033][d][osd/libass] ASS library version: 0x1502000 (runtime 0x1502000)
[   0.033][v][osd/libass] libass API version: 0x1502000
[   0.033][v][osd/libass] libass source: tarball: 0.15.2
[   0.034][v][osd/libass] Shaper: FriBidi 1.0.11 (SIMPLE) HarfBuzz-ng 3.0.0 (COMPLEX)
[   0.034][v][osd/libass] Setting up fonts...
[   0.034][v][osd/libass] Using font provider coretext
[   0.034][v][osd/libass] Done.
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="input", flags=""]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="window-controls", flags=""]
[   0.035][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-visibility=auto"]
[   0.035][v][cplayer] Set property: shared-script-properties -> 1
[   0.035][d][cplayer] Run command: disable-section, flags=64, args=[name="input"]
[   0.035][d][cplayer] Run command: disable-section, flags=64, args=[name="window-controls"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"]
[   0.035][d][cplayer] Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.035][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-margins=0.000000,0.000000,0.000000,0.000000"]
[   0.035][v][cplayer] Set property: shared-script-properties -> 1
[   0.035][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-margins=0.000000,0.000000,0.000000,0.000000"]
[   0.035][v][cplayer] Set property: shared-script-properties -> 1
[   0.035][d][osc] osc_init 
[   0.036][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-margins=0.000000,0.000000,0.000000,0.000000"]
[   0.036][v][cplayer] Set property: shared-script-properties -> 1
[   0.036][d][osd/libass] ASS library version: 0x1502000 (runtime 0x1502000)
[   0.036][v][osd/libass] libass API version: 0x1502000
[   0.036][v][osd/libass] libass source: tarball: 0.15.2
[   0.036][v][osd/libass] Shaper: FriBidi 1.0.11 (SIMPLE) HarfBuzz-ng 3.0.0 (COMPLEX)
[   0.036][v][osd/libass] Setting up fonts...
[   0.037][v][osd/libass] Using font provider coretext
[   0.037][v][osd/libass] Done.
[   0.037][d][cplayer] Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"]
[   0.037][d][cplayer] Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.037][d][cplayer] Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"]
[   0.037][d][cplayer] Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"]
[   0.037][v][cplayer] Done loading scripts.
[   0.037][d][global] config path: 'watch_later' -> '-'
[   0.037][d][global] config path: 'watch_later' -> '-'
[   0.037][v][cplayer] Running hook: ytdl_hook/on_load
[   0.037][v][ytdl_hook] ytdl:// hook 
[   0.037][v][ytdl_hook] not a ytdl:// url 
[   0.037][v][file] Opening issue-3917.mkv
[   0.037][d][file] resize stream to 131072 bytes, drop 0 bytes
[   0.037][d][file] Stream opened successfully.
[   0.037][v][demux] Trying demuxers for level=normal.
[   0.037][d][demux] Trying demuxer: disc (force-level: normal)
[   0.037][d][demux] Trying demuxer: edl (force-level: normal)
[   0.037][d][demux] Trying demuxer: cue (force-level: normal)
[   0.037][d][demux] Trying demuxer: rawaudio (force-level: normal)
[   0.037][d][demux] Trying demuxer: rawvideo (force-level: normal)
[   0.037][d][demux] Trying demuxer: mkv (force-level: normal)
[   0.037][d][mkv] Found the head...
[   0.037][d][mkv] + a segment...
[   0.037][d][mkv] Parsing seek head...
[   0.037][d][mkv] |+ segment information...
[   0.037][d][mkv] float 2016.000000
[   0.037][d][mkv] | + muxing app: Lavf59.16.100
[   0.037][d][mkv] | + writing app: Lavf59.16.100
[   0.037][d][mkv] | + timecode scale: 1000000
[   0.038][d][mkv] | + duration: 2.016s
[   0.038][d][mkv] | + segment uid a6 15 cb 48 49 4d f5 1a ea 16 71 7e 18 d1 c6 f5
[   0.038][d][mkv] |+ segment tracks...
[   0.038][d][mkv] float 48000.000000
[   0.038][d][mkv] | + a track...
[   0.038][d][mkv] |  + Track number: 1
[   0.038][d][mkv] |  + Track type: Video
[   0.038][d][mkv] |  + Video track
[   0.038][d][mkv] |   + Pixel width: 3840
[   0.038][d][mkv] |   + Pixel height: 2160
[   0.038][d][mkv] |    + Matrix: bt.709
[   0.038][d][mkv] |    + Primaries: bt.709
[   0.038][d][mkv] |    + Gamma: bt.1886
[   0.038][d][mkv] |    + Levels: limited
[   0.038][d][mkv] |  + Codec ID: V_MPEG4/ISO/AVC
[   0.038][d][mkv] |  + CodecPrivate, length 61
[   0.038][d][mkv] |  + Language: und
[   0.038][d][mkv] |  + Default duration: 16.667ms ( = 60.000 fps)
[   0.038][d][mkv] | + a track...
[   0.038][d][mkv] |  + Track number: 2
[   0.038][d][mkv] |  + Track type: Audio
[   0.038][d][mkv] |  + Audio track
[   0.038][d][mkv] |   + Sampling frequency: 48000.000000
[   0.038][d][mkv] |   + Bit depth: 32
[   0.038][d][mkv] |   + Channels: 2
[   0.038][d][mkv] |  + Codec ID: A_AAC
[   0.038][d][mkv] |  + CodecPrivate, length 5
[   0.038][d][mkv] |  + Language: und
[   0.038][d][mkv] |+ found cluster
[   0.038][v][mkv] Deferring reading cues.
[   0.038][v][mkv] All headers are parsed!
[   0.040][v][demux] Detected file format: Matroska
[   0.040][v][cplayer] Opening done: issue-3917.mkv
[   0.040][v][find_files] Loading external files in .
[   0.040][d][find_files] Potential external file: "[1]Airplane.1980.BluRay.720p.DTS.x264-CHD.eng.srt"  Priority: 0
[   0.040][d][find_files] Potential external file: "1.srt"  Priority: 0
[   0.040][d][find_files] Potential external file: "2.srt"  Priority: 0
[   0.040][d][find_files] Potential external file: "[3]The Man In The Gray Flannel Suit.srt"  Priority: 0
[   0.040][v][cplayer] Running hook: ytdl_hook/on_preloaded
[   0.040][v][mkv] select track 0
[   0.040][v][mkv] select track 1
[   0.040][i][cplayer]  (+) Video --vid=1 (*) (h264 3840x2160 60.000fps)
[   0.040][i][cplayer]  (+) Audio --aid=1 (*) (aac 2ch 48000Hz)
[   0.041][d][vo/libmpv] max content size: 3456x2068
[   0.041][d][vo/libmpv] monitor size: 3456x2068
[   0.044][v][osx/cocoacb] Can't find an ambient light sensor
[   0.044][v][vo/libmpv] Assuming 60.000002 FPS for display sync.
[   0.044][v][vd] Container reported FPS: 60.000002
[   0.044][v][vd] Codec list:
[   0.044][v][vd]     h264 - H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
[   0.044][v][vd] Opening decoder h264
[   0.044][v][vd] Looking at hwdec h264-videotoolbox...
[   0.044][v][vd] Trying hardware decoding via h264-videotoolbox.
[   0.048][v][vd] Pixel formats supported by decoder: videotoolbox_vld yuv420p
[   0.048][v][vd] Codec profile: High 4:4:4 Predictive (0xf4)
[   0.048][v][vd] Requesting pixfmt 'videotoolbox_vld' from decoder.
[   0.108][d][ffmpeg/video] h264: Reinit context to 3840x2160, pix_fmt: videotoolbox_vld
[   0.146][v][vd] Selected codec: h264 (H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10)
[   0.146][v][vf] User filter list:
[   0.146][v][vf]   (empty)
[   0.146][v][ad] Codec list:
[   0.146][v][ad]     aac - AAC (Advanced Audio Coding)
[   0.146][v][ad]     aac_fixed (aac) - AAC (Advanced Audio Coding)
[   0.146][v][ad]     aac_at (aac) - aac (AudioToolbox)
[   0.146][v][ad] Opening decoder aac
[   0.146][v][ad] Requesting 1 threads for decoding.
[   0.147][v][ad] Selected codec: aac (AAC (Advanced Audio Coding))
[   0.147][v][af] User filter list:
[   0.147][v][af]   (empty)
[   0.147][v][cplayer] Starting playback...
[   0.152][v][vd] Pixel formats supported by decoder: videotoolbox_vld yuv420p
[   0.152][v][vd] Codec profile: High 4:4:4 Predictive (0xf4)
[   0.152][v][vd] Requesting pixfmt 'videotoolbox_vld' from decoder.
[   0.178][d][ffmpeg/video] h264: Reinit context to 3840x2160, pix_fmt: videotoolbox_vld
[   0.214][i][vd] Using hardware decoding (videotoolbox).
[   0.214][v][vd] Decoder format: 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/auto CL=mpeg2/4/h264
[   0.214][v][vd] Using container aspect ratio.
[   0.214][v][vf] [in] 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.214][v][vf] [userdeint] 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.214][v][vf] [userdeint] (disabled)
[   0.214][v][vf] [autorotate] 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.214][v][vf] [autorotate] (disabled)
[   0.214][v][vf] [convert] 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.214][v][vf] [convert] (disabled)
[   0.214][v][vf] [out] 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.214][v][af] [in] 48000Hz stereo 2ch floatp
[   0.214][v][af] [userspeed] 48000Hz stereo 2ch floatp
[   0.214][v][af] [userspeed] (disabled)
[   0.214][v][af] [convert] 48000Hz stereo 2ch floatp
[   0.214][d][osc] osc_init 
[   0.214][v][ao] Trying audio driver 'coreaudio'
[   0.214][v][ao/coreaudio] requested format: 48000 Hz, stereo channels, floatp
[   0.224][v][ao/coreaudio] selected audio output device: MacBook Pro Speakers (44)
[   0.224][v][ao/coreaudio] input channel layout:
[   0.224][v][ao/coreaudio] layout: tag: <0>, bitmap: <0>, descriptions <2>
[   0.224][v][ao/coreaudio]  - description 0: label <4294967295, 64>,  flags: <0>, coords: <0.000000, 0.000000, 0.000000>
[   0.224][v][ao/coreaudio]  - description 1: label <4294967295, 64>,  flags: <0>, coords: <0.000000, 0.000000, 0.000000>
[   0.224][v][ao/coreaudio] mp chmap: unknown2
[   0.225][v][ao/coreaudio] input channel layout:
[   0.225][v][ao/coreaudio] layout: tag: <0>, bitmap: <0>, descriptions <2>
[   0.225][v][ao/coreaudio]  - description 0: label <1, 0>,  flags: <0>, coords: <0.000000, 0.000000, 0.000000>
[   0.225][v][ao/coreaudio]  - description 1: label <2, 1>,  flags: <0>, coords: <0.000000, 0.000000, 0.000000>
[   0.225][v][ao/coreaudio] mp chmap: stereo
[   0.225][v][ao/coreaudio] Channel layouts:
[   0.225][v][ao/coreaudio]  - stereo
[   0.225][v][ao/coreaudio]  - mono
[   0.225][v][ao/coreaudio] result: stereo
[   0.232][v][ao/coreaudio] Latency property cntl: 70 frames
[   0.232][v][ao/coreaudio] Latency property zisf: 512 frames
[   0.232][v][ao/coreaudio] Latency property tfas: 74 frames
[   0.232][v][ao/coreaudio] audiounit latency [us]: 0
[   0.232][v][ao/coreaudio] device latency [us]: 13666
[   0.232][v][ao/coreaudio] using soft-buffer of 9600 samples.
[   0.232][i][cplayer] AO: [coreaudio] 48000Hz stereo 2ch floatp
[   0.232][v][cplayer] AO: Description: CoreAudio AudioUnit
[   0.232][v][af] [convert] (disabled)
[   0.232][v][af] [out] 48000Hz stereo 2ch floatp
[   0.238][w][ffmpeg/video] h264: Increasing reorder buffer to 1
[   0.300][i][cplayer] VO: [libmpv] 3840x2160 videotoolbox[nv12]
[   0.300][v][cplayer] VO: Description: render API for libmpv
[   0.300][v][vo/libmpv] reconfig to 3840x2160 videotoolbox[nv12] bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
[   0.325][d][vo/libmpv] max content size: 3456x2068
[   0.325][d][vo/libmpv] monitor size: 3456x2068
[   0.343][v][libmpv_render] Testing FBO format rgba16f
[   0.343][d][libmpv_render] Resizing texture: 16x16
[   0.343][v][libmpv_render] Using FBO format rgba16f.
[   0.343][v][libmpv_render] No advanced processing required. Enabling dumb mode.
[   0.353][v][cplayer] first video frame after restart shown
[   0.353][d][cplayer] Run command: script-binding, flags=73, args=[name="osc/__keybinding4"]
[   0.353][v][cplayer] audio ready
[   0.353][d][cplayer] starting video playback
[   0.353][v][cplayer] starting audio playback
[   0.354][v][libmpv_render] Window size: 7680x4320 (Borders: l=0 t=0 r=0 b=0)
[   0.354][v][libmpv_render] Video source: 3840x2160 (1:1)
[   0.354][v][libmpv_render] Video display: (0, 0) 3840x2160 -> (0, 0) 7680x4320
[   0.354][v][libmpv_render] Video scale: 2.000000/2.000000
[   0.354][v][libmpv_render] OSD borders: l=0 t=0 r=0 b=0
[   0.354][v][libmpv_render] Video borders: l=0 t=0 r=0 b=0
[   0.356][d][libmpv_render] vertex shader source:
[   0.356][d][libmpv_render] [  1] #version 410
[   0.356][d][libmpv_render] [  2] #define tex1D texture
[   0.356][d][libmpv_render] [  3] #define tex3D texture
[   0.356][d][libmpv_render] [  4] #define LUT_POS(x, lut_size) mix(0.5 / (lut_size), 1.0 - 0.5 / (lut_size), (x))
[   0.356][d][libmpv_render] [  5] in vec2 vertex_position;
[   0.356][d][libmpv_render] [  6] in vec2 vertex_texcoord0;
[   0.356][d][libmpv_render] [  7] out vec2 texcoord0;
[   0.356][d][libmpv_render] [  8] in vec2 vertex_texcoord1;
[   0.356][d][libmpv_render] [  9] out vec2 texcoord1;
[   0.356][d][libmpv_render] [ 10] void main() {
[   0.356][d][libmpv_render] [ 11] gl_Position = vec4(vertex_position, 1.0, 1.0);
[   0.356][d][libmpv_render] [ 12] texcoord0 = vertex_texcoord0;
[   0.356][d][libmpv_render] [ 13] texcoord1 = vertex_texcoord1;
[   0.356][d][libmpv_render] [ 14] }
[   0.357][d][libmpv_render] fragment shader source:
[   0.357][d][libmpv_render] [  1] #version 410
[   0.357][d][libmpv_render] [  2] #define tex1D texture
[   0.357][d][libmpv_render] [  3] #define tex3D texture
[   0.357][d][libmpv_render] [  4] #define LUT_POS(x, lut_size) mix(0.5 / (lut_size), 1.0 - 0.5 / (lut_size), (x))
[   0.357][d][libmpv_render] [  5] out vec4 out_color;
[   0.357][d][libmpv_render] [  6] in vec2 texcoord0;
[   0.357][d][libmpv_render] [  7] in vec2 texcoord1;
[   0.357][d][libmpv_render] [  8] uniform mat3 colormatrix;
[   0.357][d][libmpv_render] [  9] uniform vec3 colormatrix_c;
[   0.357][d][libmpv_render] [ 10] uniform vec3 src_luma;
[   0.357][d][libmpv_render] [ 11] uniform vec3 dst_luma;
[   0.357][d][libmpv_render] [ 12] uniform sampler2DRect texture0;
[   0.357][d][libmpv_render] [ 13] uniform vec2 texture_size0;
[   0.357][d][libmpv_render] [ 14] uniform mat2 texture_rot0;
[   0.357][d][libmpv_render] [ 15] uniform vec2 texture_off0;
[   0.357][d][libmpv_render] [ 16] uniform vec2 pixel_size0;
[   0.357][d][libmpv_render] [ 17] uniform sampler2DRect texture1;
[   0.357][d][libmpv_render] [ 18] uniform vec2 texture_size1;
[   0.357][d][libmpv_render] [ 19] uniform mat2 texture_rot1;
[   0.357][d][libmpv_render] [ 20] uniform vec2 texture_off1;
[   0.357][d][libmpv_render] [ 21] uniform vec2 pixel_size1;
[   0.357][d][libmpv_render] [ 22] void main() {
[   0.357][d][libmpv_render] [ 23] vec4 color = vec4(0.0, 0.0, 0.0, 1.0);
[   0.357][d][libmpv_render] [ 24] color.r = 1.000000 * vec4(texture(texture0, texcoord0)).r;
[   0.357][d][libmpv_render] [ 25] color.gb = 1.000000 * vec4(texture(texture1, texcoord1)).rg;
[   0.357][d][libmpv_render] [ 26] color = color.rgbr;
[   0.357][d][libmpv_render] [ 27] color.rgb = mat3(colormatrix) * color.rgb + colormatrix_c;
[   0.357][d][libmpv_render] [ 28] color.a = 1.0;
[   0.357][d][libmpv_render] [ 29] // color mapping
[   0.357][d][libmpv_render] [ 30] color.rgb *= vec3(1.000000);
[   0.357][d][libmpv_render] [ 31] color.rgb *= vec3(1.000000);
[   0.357][d][libmpv_render] [ 32] out_color = color;
[   0.357][d][libmpv_render] [ 33] }
[   0.358][d][libmpv_render] shader link log (status=1): 
[   0.379][v][cplayer] playback restart complete @ 0.000000, audio=playing, video=playing
[   0.499][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-margins=0.000000,0.000000,0.000000,0.000000"]
[   0.499][v][cplayer] Set property: shared-script-properties -> 1
[   0.565][d][osc] osc_init 
[   0.744][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="append", value="osc-margins=0.000000,0.000000,0.000000,0.000000"]
[   0.744][v][cplayer] Set property: shared-script-properties -> 1
[   1.813][w][cplayer] 
[   1.813][w][cplayer] Audio/Video desynchronisation detected! Possible reasons include too slow
[   1.813][w][cplayer] hardware, temporary CPU spikes, broken drivers, and broken files. Audio
[   1.813][w][cplayer] position will not match to the video (see A-V status field).
[   1.813][w][cplayer] 
[   2.059][w][mkv] Too many packets in the demuxer packet queues:
[   2.059][w][mkv]   video/0: 50 packets, 159526928 bytes
[   2.059][w][mkv]   audio/1: 0 packets, 0 bytes
[   2.110][v][af] filter input EOF
[   2.110][v][af] filter output EOF
[   2.110][v][cplayer] audio filter EOF
[   2.110][v][cplayer] audio draining
[   2.110][v][cplayer] audio EOF reached
[   2.134][v][demux_in] unset EOF on stream 1
[   2.134][v][cplayer] new audio frame after EOF
[   2.135][v][cplayer] blocked, waiting for old audio to play
[   2.160][v][cplayer] blocked, waiting for old audio to play
[   2.184][v][cplayer] blocked, waiting for old audio to play
[   2.209][v][cplayer] blocked, waiting for old audio to play
[   2.236][v][cplayer] blocked, waiting for old audio to play
[   2.268][v][cplayer] blocked, waiting for old audio to play
[   2.299][v][cplayer] blocked, waiting for old audio to play
[   2.307][v][cplayer] blocked, waiting for old audio to play
[   2.308][v][mkv] EOF reached.
[   2.333][v][af] filter input EOF
[   2.333][v][af] filter output EOF
[   2.333][v][cplayer] audio filter EOF
[   2.363][v][cplayer] audio ready (and EOF)
[   2.363][v][cplayer] delaying audio start 1.912667 vs. 1.234000, diff=0.678667
[   2.390][v][cplayer] delaying audio start 1.912667 vs. 1.250000, diff=0.662667
[   2.419][v][cplayer] delaying audio start 1.912667 vs. 1.267000, diff=0.645667
[   2.448][v][cplayer] delaying audio start 1.912667 vs. 1.284000, diff=0.628667
[   2.456][v][cplayer] delaying audio start 1.912667 vs. 1.300000, diff=0.612667
[   2.485][v][cplayer] delaying audio start 1.912667 vs. 1.317000, diff=0.595667
[   2.514][v][cplayer] delaying audio start 1.912667 vs. 1.334000, diff=0.578667
[   2.542][v][cplayer] delaying audio start 1.912667 vs. 1.350000, diff=0.562667
[   2.574][v][cplayer] delaying audio start 1.912667 vs. 1.367000, diff=0.545667
[   2.590][v][cplayer] delaying audio start 1.912667 vs. 1.384000, diff=0.528667
[   2.618][v][cplayer] delaying audio start 1.912667 vs. 1.400000, diff=0.512667
[   2.643][v][cplayer] delaying audio start 1.912667 vs. 1.417000, diff=0.495667
[   2.668][v][cplayer] delaying audio start 1.912667 vs. 1.434000, diff=0.478667
[   2.686][v][cplayer] delaying audio start 1.912667 vs. 1.450000, diff=0.462667
[   2.708][v][cplayer] delaying audio start 1.912667 vs. 1.467000, diff=0.445667
[   2.737][v][cplayer] delaying audio start 1.912667 vs. 1.484000, diff=0.428667
[   2.764][v][cplayer] delaying audio start 1.912667 vs. 1.500000, diff=0.412667
[   2.791][v][cplayer] delaying audio start 1.912667 vs. 1.517000, diff=0.395667
[   2.820][v][cplayer] delaying audio start 1.912667 vs. 1.534000, diff=0.378667
[   2.848][v][cplayer] delaying audio start 1.912667 vs. 1.550000, diff=0.362667
[   2.877][v][cplayer] delaying audio start 1.912667 vs. 1.567000, diff=0.345667
[   2.908][v][cplayer] delaying audio start 1.912667 vs. 1.584000, diff=0.328667
[   2.936][v][cplayer] delaying audio start 1.912667 vs. 1.600000, diff=0.312667
[   2.967][v][cplayer] delaying audio start 1.912667 vs. 1.617000, diff=0.295667
[   2.998][v][cplayer] delaying audio start 1.912667 vs. 1.634000, diff=0.278667
[   3.028][v][cplayer] delaying audio start 1.912667 vs. 1.650000, diff=0.262667
[   3.056][v][cplayer] delaying audio start 1.912667 vs. 1.667000, diff=0.245667
[   3.091][v][cplayer] delaying audio start 1.912667 vs. 1.684000, diff=0.228667
[   3.120][v][cplayer] delaying audio start 1.912667 vs. 1.700000, diff=0.212667
[   3.155][v][cplayer] delaying audio start 1.912667 vs. 1.717000, diff=0.195667
[   3.188][v][cplayer] delaying audio start 1.912667 vs. 1.734000, diff=0.178667
[   3.217][v][cplayer] delaying audio start 1.912667 vs. 1.750000, diff=0.162667
[   3.257][v][cplayer] delaying audio start 1.912667 vs. 1.767000, diff=0.145667
[   3.293][v][cplayer] delaying audio start 1.912667 vs. 1.784000, diff=0.128667
[   3.328][v][cplayer] delaying audio start 1.912667 vs. 1.800000, diff=0.112667
[   3.362][v][cplayer] delaying audio start 1.912667 vs. 1.817000, diff=0.095667
[   3.394][v][cplayer] delaying audio start 1.912667 vs. 1.834000, diff=0.078667
[   3.426][v][cplayer] delaying audio start 1.912667 vs. 1.850000, diff=0.062667
[   3.460][v][cplayer] delaying audio start 1.912667 vs. 1.867000, diff=0.045667
[   3.497][v][cplayer] delaying audio start 1.912667 vs. 1.884000, diff=0.028667
[   3.537][v][cplayer] delaying audio start 1.912667 vs. 1.900000, diff=0.012667
[   3.572][v][cplayer] starting audio playback
[   3.572][v][cplayer] audio draining
[   3.572][v][cplayer] audio EOF reached
[   3.687][v][vf] filter input EOF
[   3.687][v][vf] filter output EOF
[   3.687][v][cplayer] EOF code: 1  
[   3.687][d][ad] Uninit decoder.
[   3.687][d][vd] Uninit decoder.
[   3.694][d][cplayer] Terminating demuxers...
[   3.695][d][cplayer] Done terminating demuxers.
[   3.695][v][cplayer] finished playback, success (reason 0)
[   3.695][i][cplayer] 
[   3.695][i][cplayer] Exiting... (End of file)
[   3.695][d][cplayer] Run command: change-list, flags=64, args=[name="shared-script-properties", operation="remove", value="osc-margins"]
[   3.695][d][console] Exiting...
[   3.695][d][ytdl_hook] Exiting...
[   3.695][v][cplayer] Set property: shared-script-properties -> 1
[   3.695][d][stats] Exiting...
[   3.696][d][osc] Exiting...
[   3.892][v][vo/libmpv] mpv_render_report_swap() not being called.
[   3.893][d][libmpv_render] flushing shader cache
[   3.893][d][osx] Exiting...

Sample files

I cut the end off the file that was submitted to IINA using ffmpeg, but it is still 400 MB and therefore won't be retained for very long.

https://0x0.st/opc7.mkv

@low-batt
Copy link
Contributor Author

@KrisCris Can you please upload the full file to onedrive again?

@KrisCris
Copy link

KrisCris commented Sep 2, 2022

@KrisCris Can you please upload the full file to onedrive again?

https://1drv.ms/u/s!AjNwqu54S0Djh6wDhyrjwkf182R3uQ?e=BdqZUZ

@Akemi
Copy link
Member

Akemi commented Mar 7, 2024

does anyone of you still have the sample file laying around somewhere?

@low-batt
Copy link
Contributor Author

low-batt commented Mar 7, 2024

First two tries to upload failed, but 3rd attempt worked. This is the version of the user's original file that I cut the end off to get the size down to 400 MB.:

https://0x0.st/opc7.mkv

I ran a couple quick tests with a brew installed mpv 0.37.0. Playback was fine without hardware decode. Adding --hwdec=videotoolbox caused audio to cut off near the end of the video and reproduced the error:

low-batt@gag issue-3917$ mpv --no-config --log-file=test.log --hwdec=videotoolbox low-batt-issue-3917.mkv 
 (+) Video --vid=1 (*) (h264 3840x2160 60.000fps)
 (+) Audio --aid=1 (*) (aac 2ch 48000Hz)
Using hardware decoding (videotoolbox).
AO: [coreaudio] 48000Hz stereo 2ch floatp
[ffmpeg/video] h264: Increasing reorder buffer to 1
VO: [libmpv] 3840x2160 videotoolbox[nv12]
                                                      
Audio/Video desynchronisation detected! Possible reasons include too slow
hardware, temporary CPU spikes, broken drivers, and broken files. Audio
position will not match to the video (see A-V status field).
Consider trying `--profile=fast` and/or `--hwdec=auto-safe` as they may help.

[mkv] Too many packets in the demuxer packet queues:  
[mkv]   video/0: 50 packets, 157860112 bytes          
[mkv]   audio/1: 0 packets, 0 bytes                   
Exiting... (End of file)                              
low-batt@gag issue-3917$                              

This is the log file from that test:
failed.log

@Akemi
Copy link
Member

Akemi commented Mar 10, 2024

thanks for the sample, i will keep it too if the download is unavailable again.

the actual problem here is ffmpeg and/or videotoolbox. the sample can't be decoded fast enough with videotoolbox and our core code that tries to time video frames to audio just stalls or rather can't get the video frames fast enough to properly time.

the crucial part in your logs is from ffmpeg (see below). it can only decode the video stream with 34fps using videotoolbox, though the video is 60fps, so it result in a decoding speed of 0.562x. we at least need a speed of >=1 to properly time and show frames.

frame=  121 fps= 34 q=-0.0 Lsize=N/A time=00:00:02.00 bitrate=N/A speed=0.562x

on my system it's similar slow and i get the same problems. with software decoding, on the other hand, i get a speed of >=3.
other samples decode with >=8 speed with videotoolbox.

Software Decoding:
akemi@Mac-Studio ~ % ffmpeg -i /Users/Akemi/Downloads/opc7.mkv -f null -
ffmpeg version 6.1.1 Copyright (c) 2000-2023 the FFmpeg developers
  built with Apple clang version 15.0.0 (clang-1500.1.0.2.5)
  configuration: --prefix=/opt/homebrew/Cellar/ffmpeg/6.1.1_3 --enable-shared --enable-pthreads --enable-version3 --cc=clang --host-cflags= --host-ldflags='-Wl,-ld_classic' --enable-ffplay --enable-gnutls --enable-gpl --enable-libaom --enable-libaribb24 --enable-libbluray --enable-libdav1d --enable-libharfbuzz --enable-libjxl --enable-libmp3lame --enable-libopus --enable-librav1e --enable-librist --enable-librubberband --enable-libsnappy --enable-libsrt --enable-libssh --enable-libsvtav1 --enable-libtesseract --enable-libtheora --enable-libvidstab --enable-libvmaf --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-lzma --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopenvino --enable-libspeex --enable-libsoxr --enable-libzmq --enable-libzimg --disable-libjack --disable-indev=jack --enable-videotoolbox --enable-audiotoolbox --enable-neon
  libavutil      58. 29.100 / 58. 29.100
  libavcodec     60. 31.102 / 60. 31.102
  libavformat    60. 16.100 / 60. 16.100
  libavdevice    60.  3.100 / 60.  3.100
  libavfilter     9. 12.100 /  9. 12.100
  libswscale      7.  5.100 /  7.  5.100
  libswresample   4. 12.100 /  4. 12.100
  libpostproc    57.  3.100 / 57.  3.100
Input #0, matroska,webm, from '/Users/Akemi/Downloads/opc7.mkv':
  Metadata:
    ENCODER         : Lavf59.16.100
  Duration: 00:00:02.02, bitrate: 1576973 kb/s
  Stream #0:0: Video: h264 (High 4:4:4 Predictive), yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], 60 fps, 60 tbr, 1k tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
  Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp (default)
    Metadata:
      DURATION        : 00:00:01.998000000
Stream mapping:
  Stream #0:0 -> #0:0 (h264 (native) -> wrapped_avframe (native))
  Stream #0:1 -> #0:1 (aac (native) -> pcm_s16le (native))
Press [q] to stop, [?] for help
Output #0, null, to 'pipe:':
  Metadata:
    encoder         : Lavf60.16.100
  Stream #0:0: Video: wrapped_avframe, yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], q=2-31, 200 kb/s, 60 fps, 60 tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
      encoder         : Lavc60.31.102 wrapped_avframe
  Stream #0:1: Audio: pcm_s16le, 48000 Hz, stereo, s16, 1536 kb/s (default)
    Metadata:
      DURATION        : 00:00:01.998000000
      encoder         : Lavc60.31.102 pcm_s16le
[out#0/null @ 0x60000299ca80] video:57kB audio:380kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
frame=  121 fps=0.0 q=-0.0 Lsize=N/A time=00:00:02.00 bitrate=N/A speed=3.01x    

if we use a different timing mode, for example one where we don't try to time video and audio in some way (--video-sync=display-desync), we don't get any errors/warnings anymore, though the video and audio gets out of sync.

because ffmpeg/videotoolbox is too slow we can't really handle this without any kind of problems/issues. either we get those errors, drop a lot of video/audio frames or let the playback desync.

for me that is an up-/downstream problem, if it is even supposed to handle such a sample. it has quite the massive bitrate (1576973 kb/s, nearly 200MB/s?).

@low-batt
Copy link
Contributor Author

I'm troubled by VLC behavior. VLC is able to play the file without any problems. I have confirmed that is because VLC is not using hardware acceleration. I also confirmed that hardware acceleration is enabled and it will use it for other files. Makes me wonder whether it is keying off something about the encoding and deciding hardware acceleration is inappropriate for this file.

I'm testing with a MacBookPro18,2 with the Apple M1 Max chip running macOS Ventura 13.6.3. As shown below I see the same massive reduction in decoding speed with hardware acceleration when using FFmpeg directly.

The man page for ffmpeg contains an interesting note under the -hwaccel option found here:

Note that most acceleration methods are intended for playback and will not be faster than software decoding on modern CPUs. Additionally, ffmpeg will usually need to copy the decoded frames from the GPU memory into the system memory, resulting in further performance loss. This option is thus mainly useful for testing.

As all the Internet posts talk about how much better hardware acceleration is, I've not given much thought to the limits of the Apple "Media Engine". Not helpful that Apple does not publish any technical details about this portion of the chip. But I guess it makes sense that at some point the 10 CPUs might be able to do better than the "Media Engine".

I have an 8K VP9 encoded 60 fps video that also exhibits problems. With hardware acceleration playback stutters and frames are being dropped. Desynchronization is reported, but not the "Too many packets" error. Possibly this is also running into the limits of the Media Engine.

With Hardware Acceleration:
low-batt@gag issue-3917$ time ffmpeg -hwaccel videotoolbox -i low-batt-issue-3917.mkv -benchmark -f null -
ffmpeg version 6.1.1 Copyright (c) 2000-2023 the FFmpeg developers
  built with Apple clang version 15.0.0 (clang-1500.1.0.2.5)
  configuration: --prefix=/opt/homebrew/Cellar/ffmpeg/6.1.1_3 --enable-shared --enable-pthreads --enable-version3 --cc=clang --host-cflags= --host-ldflags='-Wl,-ld_classic' --enable-ffplay --enable-gnutls --enable-gpl --enable-libaom --enable-libaribb24 --enable-libbluray --enable-libdav1d --enable-libharfbuzz --enable-libjxl --enable-libmp3lame --enable-libopus --enable-librav1e --enable-librist --enable-librubberband --enable-libsnappy --enable-libsrt --enable-libssh --enable-libsvtav1 --enable-libtesseract --enable-libtheora --enable-libvidstab --enable-libvmaf --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-lzma --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopenvino --enable-libspeex --enable-libsoxr --enable-libzmq --enable-libzimg --disable-libjack --disable-indev=jack --enable-videotoolbox --enable-audiotoolbox --enable-neon
  libavutil      58. 29.100 / 58. 29.100
  libavcodec     60. 31.102 / 60. 31.102
  libavformat    60. 16.100 / 60. 16.100
  libavdevice    60.  3.100 / 60.  3.100
  libavfilter     9. 12.100 /  9. 12.100
  libswscale      7.  5.100 /  7.  5.100
  libswresample   4. 12.100 /  4. 12.100
  libpostproc    57.  3.100 / 57.  3.100
Input #0, matroska,webm, from 'low-batt-issue-3917.mkv':
  Metadata:
    ENCODER         : Lavf59.16.100
  Duration: 00:00:02.02, bitrate: 1576973 kb/s
  Stream #0:0: Video: h264 (High 4:4:4 Predictive), yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], 60 fps, 60 tbr, 1k tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
  Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp (default)
    Metadata:
      DURATION        : 00:00:01.998000000
Stream mapping:
  Stream #0:0 -> #0:0 (h264 (native) -> wrapped_avframe (native))
  Stream #0:1 -> #0:1 (aac (native) -> pcm_s16le (native))
Press [q] to stop, [?] for help
Output #0, null, to 'pipe:':
  Metadata:
    encoder         : Lavf60.16.100
  Stream #0:0: Video: wrapped_avframe, nv12(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], q=2-31, 200 kb/s, 60 fps, 60 tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
      encoder         : Lavc60.31.102 wrapped_avframe
  Stream #0:1: Audio: pcm_s16le, 48000 Hz, stereo, s16, 1536 kb/s (default)
    Metadata:
      DURATION        : 00:00:01.998000000
      encoder         : Lavc60.31.102 pcm_s16le
[out#0/null @ 0x600000008480] video:57kB audio:380kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
frame=  121 fps= 34 q=-0.0 Lsize=N/A time=00:00:02.00 bitrate=N/A speed=0.559x    
bench: utime=0.713s stime=0.474s rtime=3.575s
bench: maxrss=788971520kB

real	0m3.887s
user	0m0.931s
sys	0m0.538s
low-batt@gag issue-3917$ 
Without Hardware Acceleration:
low-batt@gag issue-3917$ time ffmpeg -i low-batt-issue-3917.mkv -benchmark -f null -
ffmpeg version 6.1.1 Copyright (c) 2000-2023 the FFmpeg developers
  built with Apple clang version 15.0.0 (clang-1500.1.0.2.5)
  configuration: --prefix=/opt/homebrew/Cellar/ffmpeg/6.1.1_3 --enable-shared --enable-pthreads --enable-version3 --cc=clang --host-cflags= --host-ldflags='-Wl,-ld_classic' --enable-ffplay --enable-gnutls --enable-gpl --enable-libaom --enable-libaribb24 --enable-libbluray --enable-libdav1d --enable-libharfbuzz --enable-libjxl --enable-libmp3lame --enable-libopus --enable-librav1e --enable-librist --enable-librubberband --enable-libsnappy --enable-libsrt --enable-libssh --enable-libsvtav1 --enable-libtesseract --enable-libtheora --enable-libvidstab --enable-libvmaf --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libxvid --enable-lzma --enable-libfontconfig --enable-libfreetype --enable-frei0r --enable-libass --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopenvino --enable-libspeex --enable-libsoxr --enable-libzmq --enable-libzimg --disable-libjack --disable-indev=jack --enable-videotoolbox --enable-audiotoolbox --enable-neon
  libavutil      58. 29.100 / 58. 29.100
  libavcodec     60. 31.102 / 60. 31.102
  libavformat    60. 16.100 / 60. 16.100
  libavdevice    60.  3.100 / 60.  3.100
  libavfilter     9. 12.100 /  9. 12.100
  libswscale      7.  5.100 /  7.  5.100
  libswresample   4. 12.100 /  4. 12.100
  libpostproc    57.  3.100 / 57.  3.100
Input #0, matroska,webm, from 'low-batt-issue-3917.mkv':
  Metadata:
    ENCODER         : Lavf59.16.100
  Duration: 00:00:02.02, bitrate: 1576973 kb/s
  Stream #0:0: Video: h264 (High 4:4:4 Predictive), yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], 60 fps, 60 tbr, 1k tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
  Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp (default)
    Metadata:
      DURATION        : 00:00:01.998000000
Stream mapping:
  Stream #0:0 -> #0:0 (h264 (native) -> wrapped_avframe (native))
  Stream #0:1 -> #0:1 (aac (native) -> pcm_s16le (native))
Press [q] to stop, [?] for help
Output #0, null, to 'pipe:':
  Metadata:
    encoder         : Lavf60.16.100
  Stream #0:0: Video: wrapped_avframe, yuv420p(tv, bt709, progressive), 3840x2160 [SAR 1:1 DAR 16:9], q=2-31, 200 kb/s, 60 fps, 60 tbn (default)
    Metadata:
      DURATION        : 00:00:02.016000000
      encoder         : Lavc60.31.102 wrapped_avframe
  Stream #0:1: Audio: pcm_s16le, 48000 Hz, stereo, s16, 1536 kb/s (default)
    Metadata:
      DURATION        : 00:00:01.998000000
      encoder         : Lavc60.31.102 pcm_s16le
[out#0/null @ 0x6000007209c0] video:57kB audio:380kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
frame=  121 fps=109 q=-0.0 Lsize=N/A time=00:00:02.00 bitrate=N/A speed= 1.8x    
bench: utime=9.738s stime=0.216s rtime=1.109s
bench: maxrss=684965888kB

real	0m1.391s
user	0m9.957s
sys	0m0.266s
low-batt@gag issue-3917$ 

@Akemi
Copy link
Member

Akemi commented Mar 16, 2024

yeah i can confirm the behaviour of VLC. it was the same on my end when i tried it.

this is just a guess here since i am not too familiar with the VLC code. VLC is checking the profile here. the file itself is "High 4:4:4 Predictive@L5.2", but PROFILE_H264_HIGH_444_PREDICTIVE is not 'whitelisted'?

closing this since it's not really a mpv problem. maybe it's worth bringing up on ffmpeg?

@Akemi Akemi closed this as completed Mar 16, 2024
@Akemi
Copy link
Member

Akemi commented Mar 16, 2024

also maybe it's related to https://trac.ffmpeg.org/ticket/8734#ticket which i opened.

@low-batt
Copy link
Contributor Author

I have reported this to FFmpeg in ticket #10963.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

5 participants
@Akemi @Traneptora @KrisCris @low-batt and others