Page 1 of 1

Slow start

Posted: Mon Apr 15, 2019 12:39 pm
by Bouke
Hi all,

The latest builds seem to have a very long delay before starting transcoding.
It seems not to be related on in/output codecs.
-c:v copy is fast as usual, as is just getting metadata (omitting output), but anything else has a delay of about 10 seconds (at least) between spitting out the initial setup info and the actual start of encoding.

Below is a sample output, but again, it’s not related to the codecs afaik.

Thx,

Bouke

Code: Select all

ffmpeg version N-93569-gf9840cd2b5 Copyright (c) 2000-2019 the FFmpeg developers
 built with gcc 8.3.1 (GCC) 20190414
 configuration: --enable-gpl --enable-version3 --enable-sdl2 --enable-fontconfig --enable-gnutls --enable-iconv --enable-libass --enable-libdav1d --enable-libbluray --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libtheora --enable-libtwolame --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libzimg --enable-lzma --enable-zlib --enable-gmp --enable-libvidstab --enable-libvorbis --enable-libvo-amrwbenc --enable-libmysofa --enable-libspeex --enable-libxvid --enable-libaom --enable-libmfx --enable-amf --enable-ffnvcodec --enable-cuvid --enable-d3d11va --enable-nvenc --enable-nvdec --enable-dxva2 --enable-avisynth --enable-libopenmpt
 libavutil      56. 26.100 / 56. 26.100
 libavcodec     58. 51.100 / 58. 51.100
 libavformat    58. 27.102 / 58. 27.102
 libavdevice    58.  7.100 / 58.  7.100
 libavfilter     7. 48.100 /  7. 48.100
 libswscale      5.  4.100 /  5.  4.100
 libswresample   3.  4.100 /  3.  4.100
 libpostproc    55.  4.100 / 55.  4.100
Guessed Channel Layout for Input Stream #0.1 : mono
Guessed Channel Layout for Input Stream #0.2 : mono
Guessed Channel Layout for Input Stream #0.3 : mono
Guessed Channel Layout for Input Stream #0.4 : mono
Input #0, mxf, from 'X:\Cam examples\CanonXF705\A003C032H1801230A_CANON.MXF':
 Metadata:
   operational_pattern_ul: 060e2b34.04010101.0d010201.01010900
   uid             : 4ca2d441-8105-4901-802f-ae3689900021
   generation_uid  : 4ca2d441-8105-4903-802f-ae3689900021
   company_name    : CANON
   product_name    : XF705
   product_version : 1.00
   product_uid     : 060e2b34-0401-010d-0e15-005658460400
   modification_date: 2018-01-23T23:45:08.000000Z
   material_package_umid: 0x060A2B340101010501010D4313000000CD7E1655418105800000853689900021
   timecode        : 00:25:16;23
 Duration: 00:00:32.03, start: 0.000000, bitrate: 156207 kb/s
   Stream #0:0: Video: hevc (Rext), yuv422p10le(pc, bt709/bt709/unknown, progressive), 3840x2160 [SAR 1:1 DAR 16:9], 29.97 fps, 29.97 tbr, 29.97 tbn, 29.97 tbc
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
   Stream #0:1: Audio: pcm_s24le, 48000 Hz, mono, s32 (24 bit), 1152 kb/s
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
   Stream #0:2: Audio: pcm_s24le, 48000 Hz, mono, s32 (24 bit), 1152 kb/s
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
   Stream #0:3: Audio: pcm_s24le, 48000 Hz, mono, s32 (24 bit), 1152 kb/s
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
   Stream #0:4: Audio: pcm_s24le, 48000 Hz, mono, s32 (24 bit), 1152 kb/s
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
Stream mapping:
 Stream #0:0 -> #0:0 (hevc (native) -> prores (native))
 Stream #0:1 -> #0:1 (copy)
Press [q] to stop, [?] for help
frame=    0 fps=0.0 q=0.0 size=       0kB time=-577014:32:22.77 bitrate=  -0.0kbits/s speed=N/A    
Output #0, mov, to 'X:\Cam examples\CanonXF705\A003C032H1801230A_CANON.mov':
 Metadata:
   operational_pattern_ul: 060e2b34.04010101.0d010201.01010900
   uid             : 4ca2d441-8105-4901-802f-ae3689900021
   generation_uid  : 4ca2d441-8105-4903-802f-ae3689900021
   company_name    : CANON
   product_name    : XF705
   product_version : 1.00
   product_uid     : 060e2b34-0401-010d-0e15-005658460400
   modification_date: 2018-01-23T23:45:08.000000Z
   material_package_umid: 0x060A2B340101010501010D4313000000CD7E1655418105800000853689900021
   timecode        : 00:25:16;23
   encoder         : Lavf58.27.102
   Stream #0:0: Video: prores (Proxy) (apco / 0x6F637061), yuv422p10le, 320x240 [SAR 4:3 DAR 16:9], q=2-31, 200 kb/s, 0.03 fps, 30k tbn, 29.97 tbc
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
     encoder         : Lavc58.51.100 prores
   Stream #0:1: Audio: pcm_s24le (in24 / 0x34326E69), 48000 Hz, mono, s32 (24 bit), 1152 kb/s
   Metadata:
     file_package_umid: 0x060A2B340101010501010D4313000000CD7E1654418105800000853689900021
frame=    1 fps=0.0 q=0.0 size=       0kB time=00:00:00.60 bitrate=   0.5kbits/s speed=0.0265x    
frame=   17 fps=0.7 q=-0.0 size=       0kB time=00:00:01.13 bitrate=   0.3kbits/s speed=0.0489x    
frame=   32 fps=1.3 q=-0.0 size=     256kB time=00:00:01.63 bitrate=1282.9kbits/s speed=0.0688x    
frame=   49 fps=2.0 q=-0.0 size=     512kB time=00:00:02.20 bitrate=1904.7kbits/s speed=0.0906x    
frame=   67 fps=2.7 q=-0.0 size=     768kB time=00:00:02.80 bitrate=2244.8kbits/s speed=0.113x    
frame=   83 fps=3.2 q=-0.0 size=    1024kB time=00:00:03.33 bitrate=2514.2kbits/s speed=0.131x    
frame=  102 fps=3.9 q=-0.0 size=    1280kB time=00:00:03.97 bitrate=2640.9kbits/s speed=0.152x    
frame=  120 fps=4.5 q=-0.0 size=    1536kB time=00:00:04.57 bitrate=2752.7kbits/s speed=0.171x    
frame=  143 fps=5.2 q=-0.0 size=    1792kB time=00:00:05.33 bitrate=2749.8kbits/s speed=0.196x    
frame=  158 fps=5.7 q=-0.0 size=    2048kB time=00:00:05.83 bitrate=2873.3kbits/s speed=0.21x    
frame=  180 fps=6.4 q=-0.0 size=    2304kB time=00:00:06.57 bitrate=2871.4kbits/s speed=0.232x    
frame=  192 fps=6.7 q=-0.0 size=    2560kB time=00:00:06.97 bitrate=3007.3kbits/s speed=0.242x    
frame=  215 fps=7.3 q=-0.0 size=    2816kB time=00:00:07.74 bitrate=2980.1kbits/s speed=0.263x    
frame=  229 fps=7.7 q=-0.0 size=    3072kB time=00:00:08.20 bitrate=3066.0kbits/s speed=0.274x    
frame=  247 fps=8.1 q=-0.0 size=    3328kB time=00:00:08.80 bitrate=3095.0kbits/s speed=0.288x    
frame=  268 fps=8.6 q=-0.0 size=    3584kB time=00:00:09.50 bitrate=3087.5kbits/s speed=0.306x    
frame=  286 fps=9.0 q=-0.0 size=    3840kB time=00:00:10.11 bitrate=3111.5kbits/s speed=0.319x    
frame=  305 fps=9.5 q=-0.0 size=    4096kB time=00:00:10.74 bitrate=3123.1kbits/s speed=0.334x    
frame=  318 fps=9.7 q=-0.0 size=    4096kB time=00:00:11.17 bitrate=3001.9kbits/s speed=0.342x    
frame=  337 fps= 10 q=-0.0 size=    4352kB time=00:00:11.81 bitrate=3018.3kbits/s speed=0.356x    
frame=  351 fps= 10 q=-0.0 size=    4608kB time=00:00:12.27 bitrate=3074.3kbits/s speed=0.363x    
frame=  370 fps= 11 q=-0.0 size=    4864kB time=00:00:12.91 bitrate=3085.8kbits/s speed=0.376x    
frame=  391 fps= 11 q=-0.0 size=    5120kB time=00:00:13.61 bitrate=3081.0kbits/s speed=0.39x    
frame=  408 fps= 12 q=-0.0 size=    5376kB time=00:00:14.18 bitrate=3105.6kbits/s speed=0.401x    
frame=  425 fps= 12 q=-0.0 size=    5632kB time=00:00:14.74 bitrate=3128.4kbits/s speed=0.411x    
frame=  443 fps= 12 q=-0.0 size=    5888kB time=00:00:15.34 bitrate=3142.6kbits/s speed=0.421x    
frame=  455 fps= 12 q=-0.0 size=    6144kB time=00:00:15.74 bitrate=3195.9kbits/s speed=0.425x    
frame=  478 fps= 13 q=-0.0 size=    6400kB time=00:00:16.51 bitrate=3174.3kbits/s speed=0.438x    
frame=  499 fps= 13 q=-0.0 size=    6656kB time=00:00:17.21 bitrate=3167.0kbits/s speed=0.449x    
frame=  515 fps= 13 q=-0.0 size=    6912kB time=00:00:17.75 bitrate=3189.9kbits/s speed=0.455x    
frame=  535 fps= 13 q=-0.0 size=    7168kB time=00:00:18.41 bitrate=3188.1kbits/s speed=0.465x    
frame=  551 fps= 14 q=-0.0 size=    7168kB time=00:00:18.95 bitrate=3098.3kbits/s speed=0.47x    
frame=  572 fps= 14 q=-0.0 size=    7424kB time=00:00:19.65 bitrate=3094.6kbits/s speed=0.481x    
frame=  590 fps= 14 q=-0.0 size=    7680kB time=00:00:20.25 bitrate=3106.4kbits/s speed=0.49x    
frame=  605 fps= 14 q=-0.0 size=    7936kB time=00:00:20.75 bitrate=3132.5kbits/s speed=0.496x    
frame=  622 fps= 15 q=-0.0 size=    7936kB time=00:00:21.32 bitrate=3049.2kbits/s speed=0.503x    
frame=  632 fps= 15 q=-0.0 size=    8192kB time=00:00:21.65 bitrate=3099.0kbits/s speed=0.504x    
frame=  651 fps= 15 q=-0.0 size=    8448kB time=00:00:22.28 bitrate=3105.0kbits/s speed=0.512x    
frame=  669 fps= 15 q=-0.0 size=    8448kB time=00:00:22.88 bitrate=3023.5kbits/s speed=0.519x    
frame=  685 fps= 15 q=-0.0 size=    8704kB time=00:00:23.42 bitrate=3044.1kbits/s speed=0.525x    
frame=  704 fps= 16 q=-0.0 size=    8960kB time=00:00:24.05 bitrate=3051.1kbits/s speed=0.533x    
frame=  721 fps= 16 q=-0.0 size=    8960kB time=00:00:24.62 bitrate=2980.8kbits/s speed=0.54x    
frame=  735 fps= 16 q=-0.0 size=    9216kB time=00:00:25.09 bitrate=3008.9kbits/s speed=0.542x    
frame=  751 fps= 16 q=-0.0 size=    9472kB time=00:00:25.62 bitrate=3028.0kbits/s speed=0.546x    
frame=  775 fps= 16 q=-0.0 size=    9728kB time=00:00:26.42 bitrate=3015.6kbits/s speed=0.556x    
frame=  794 fps= 17 q=-0.0 size=    9984kB time=00:00:27.06 bitrate=3022.5kbits/s speed=0.563x    
frame=  814 fps= 17 q=-0.0 size=    9984kB time=00:00:27.72 bitrate=2949.7kbits/s speed=0.571x    
frame=  830 fps= 17 q=-0.0 size=   10240kB time=00:00:28.26 bitrate=2968.2kbits/s speed=0.576x    
frame=  847 fps= 17 q=-0.0 size=   10496kB time=00:00:28.82 bitrate=2982.6kbits/s speed=0.581x    
frame=  868 fps= 17 q=-0.0 size=   10752kB time=00:00:29.52 bitrate=2982.8kbits/s speed=0.587x    
frame=  888 fps= 17 q=-0.0 size=   11008kB time=00:00:30.19 bitrate=2986.3kbits/s speed=0.595x    
frame=  902 fps= 18 q=-0.0 size=   11008kB time=00:00:30.66 bitrate=2940.8kbits/s speed=0.598x    
frame=  916 fps= 18 q=-0.0 size=   11264kB time=00:00:31.13 bitrate=2964.1kbits/s speed=0.601x    
frame=  934 fps= 18 q=-0.0 size=   11520kB time=00:00:31.73 bitrate=2974.1kbits/s speed=0.607x    
frame=  959 fps= 18 q=-0.0 size=   11776kB time=00:00:31.99 bitrate=3014.8kbits/s speed=0.606x    
frame=  960 fps= 18 q=-0.0 Lsize=   11994kB time=00:00:31.99 bitrate=3070.5kbits/s speed=0.606x    
video:7470kB audio:4504kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 0.160097%