TCP negotiation failed

[BerkayD06]

Hello,

I'm using the Jaffree library in my Spring Boot application to execute FFmpeg commands with a ProgressListener. While the same FFmpeg command works perfectly when run manually in the terminal without -progress section, it fails in my application with the following warning and error logs:

WARN --- [ Runnable-0] c.g.k.jaffree.process.Executor : Interrupting starter thread (Thread-11) because of exception: TCP negotiation failed WARN --- [ Thread-11] c.g.k.jaffree.process.ProcessHandler : Process has been interrupted DEBUG --- [ StdErr] c.g.k.jaffree.process.Executor : Exception in thread StdErr, collecting for later report. Message: Read failed

My Code is =

`FFmpeg command =
FFmpeg.atPath(Paths.get(ffmpegPath))
.addInput(UrlInput.fromUrl(job.getSource().getPath()))
.addOutput(UrlOutput.toUrl(job.getOutputPath()))
.setLogLevel(LogLevel.ERROR)
.setProgressListener(progressListener);

    if (encodePreset.isAudioEnabled()) {
        command.addArguments("-c:a", audioPreset.getCodec());
        if (audioPreset.getBitrate() > 0)
            command.addArguments("-b:a", audioPreset.getBitrate().toString());

        if (audioPreset.getSampleRate() > 0)
            command.addArguments("-ar", audioPreset.getSampleRate().toString());

        if (audioPreset.getChannel().equals("stereo")) {
            command.addArguments("-ac", "2");
        }
    } else {
        command.addArgument("-an");
    }

    if (encodePreset.isVideoEnabled()) {
        command
                .addArguments("-c:v", videoPreset.getCodec())
                .addArguments("-crf", videoPreset.getCrf().toString());

        if (videoPreset.getWidth() > 0 || videoPreset.getHeight() > 0)
            command.addArguments(
                    "-vf", "scale=" + videoPreset.getWidth() + ":" + videoPreset.getHeight());

        if (videoPreset.getBitrate() > 0)
            command.addArguments("-b:v", videoPreset.getBitrate().toString());

        if (videoPreset.getMaxrate() > 0)
            command.addArguments("-maxrate", videoPreset.getMaxrate().toString());

        if (videoPreset.getMinrate() > 0)
            command.addArguments("-minrate", videoPreset.getMinrate().toString());

        if (videoPreset.getBufsize() > 0)
            command.addArguments("-bufsize", videoPreset.getBufsize().toString());

        if (!videoPreset.getTune().equals("none"))
            command.addArguments("-tune", videoPreset.getTune());

        if (videoPreset.getCodec().equals("libx264") || videoPreset.getCodec().equals("libx265")) {

            if (!videoPreset.getProfile().equals("none"))
                command.addArguments("-profile:v", videoPreset.getProfile());

            if (!videoPreset.getPreset().equals("none"))
                command.addArguments("-preset", videoPreset.getPreset());
        }
    } else {
        command.addArgument("-vn");
    }
    boolean allTextBasedSubtitles = true;

    for (Track track : job.getSource().getTracks()) {
        if ("subtitle".equals(track.getCodecType())) {
            log.error("codec = " + track.getCodec());
            if (!isTextBasedSubtitle(track.getCodec())) {
                allTextBasedSubtitles = false;
                break;
            }
        }
    }
    command.addArguments("-map", "0:v:0");

    if (allTextBasedSubtitles) {
        command.addArguments("-c:s", "mov_text");
    } else {
        command.addArgument("-sn");
    }
    log.info("ffmpeg command = " +command);
    return command.execute();
}

 Runnable encoderThread =
            () -> {
                threadCount.incrementAndGet();
                try {
                    ProgressListener progressListener =
                            progress -> {
                                log.error("Progress: " + progress);
                                int percents =
                                        (int)
                                                (100.
                                                        * progress.getTimeMillis()
                                                        / (encodeJob.getSource().getDuration() * 1000));
                                if (percents % 10 == 0) {
                                    log.error ("progress listener");
                                    updateJob(encodeJob, JobStatus.STARTED, percents);
                                }
                            };`

ffmpeg command example = /usr/bin/ffmpeg -loglevel level+error -i /home/berkay.demirbas/test.mkv -n -progress tcp://127.0.0.1:36813 -c:a aac -b:a 128000 -ar 48000 -ac 2 -c:v libx264 -crf 17 -vf scale=1920:1080 -b:v 4500000 -maxrate 5000000 -bufsize 2250000 -tune film -profile:v baseline -map 0:v:0 -c:s mov_text /home/berkay.demirbas/test-1080p.mp4

[SalmanOz]

I'm having the same problem, did you find a solution?

[kokorin]

Please, provide exception stacktrace and ffmpeg debug logs.

[BerkayD06]

Hello kokorin In some videos I encoded, there were two video streams, and one of them was in MJPEG format, which caused an error. As a solution, when I used -map 0:v:0 to take the first video stream, I encountered the issue I mentioned earlier. Alternatively, I resolved the problem by using ffprobe to identify the sources and mapping the non-MJPEG video streams with -map 0:index. However, as I mentioned before, when I manually ran the -map 0:v:0 command directly on the server, I was able to profile without any issues.

ffmpeg debug logs =
Command constructed: /usr/bin/ffmpeg -loglevel level+debug -i /mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv -n -progress tcp://127.0.0.1:46629 -c:a aac -b:a 128000 -ar 48000 -ac 2 -c:v libx264 -crf 17 -vf scale=1280:720 -b:v 2500000 -maxrate 3000000 -bufsize 1250000 -tune film -profile:v baseline -map 0:v:0 -c:s mov_text /mnt/atlas/contents/100010260//ffmpeglog_Content-Profile-720p.mp4 2024-11-26T08:30:09.606Z INFO 1 --- [ Thread-11] c.g.k.jaffree.process.ProcessHandler : Starting process: /usr/bin/ffmpeg 2024-11-26T08:30:09.621Z DEBUG 1 --- [ Thread-11] c.g.k.jaffree.process.ProcessHandler : Starting IO interaction with process 2024-11-26T08:30:09.624Z DEBUG 1 --- [ StdErr] c.g.kokorin.jaffree.process.Executor : StdErr thread has started 2024-11-26T08:30:09.626Z DEBUG 1 --- [ StdOut] c.g.kokorin.jaffree.process.Executor : StdOut thread has started 2024-11-26T08:30:09.631Z DEBUG 1 --- [ Runnable-0] c.g.kokorin.jaffree.process.Executor : Runnable-0 thread has started 2024-11-26T08:30:09.629Z INFO 1 --- [ Thread-11] c.g.k.jaffree.process.ProcessHandler : Waiting for process to finish 2024-11-26T08:30:10.690Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] ffmpeg version 6.1.1-3ubuntu5 Copyright (c) 2000-2023 the FFmpeg developers 2024-11-26T08:30:10.690Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] built with gcc 13 (Ubuntu 13.2.0-23ubuntu3) 2024-11-26T08:30:10.698Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] configuration: --prefix=/usr --extra-version=3ubuntu5 --toolchain=hardened --libdir=/usr/lib/x86_64-linux-gnu --incdir=/usr/include/x86_64-linux-gnu --arch=amd64 --enable-gpl --disable-stripping --disable-omx --enable-gnutls --enable-libaom --enable-libass --enable-libbs2b --enable-libcaca --enable-libcdio --enable-libcodec2 --enable-libdav1d --enable-libflite --enable-libfontconfig --enable-libfreetype --enable-libfribidi --enable-libglslang --enable-libgme --enable-libgsm --enable-libharfbuzz --enable-libmp3lame --enable-libmysofa --enable-libopenjpeg --enable-libopenmpt --enable-libopus --enable-librubberband --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libspeex --enable-libtheora --enable-libtwolame --enable-libvidstab --enable-libvorbis --enable-libvpx --enable-libwebp --enable-libx265 --enable-libxml2 --enable-libxvid --enable-libzimg --enable-openal --enable-opencl --enable-opengl --disable-sndio --enable-libvpl --disable-libmfx --enable-libdc1394 --enable-libdrm --enable-libiec61883 --enable-chromaprint --enable-frei0r --enable-ladspa --enable-libbluray --enable-libjack --enable-libpulse --enable-librabbitmq --enable-librist --enable-libsrt --enable-libssh --enable-libsvtav1 --enable-libx264 --enable-libzmq --enable-libzvbi --enable-lv2 --enable-sdl2 --enable-libplacebo --enable-librav1e --enable-pocketsphinx --enable-librsvg --enable-libjxl --enable-shared 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libavutil 58. 29.100 / 58. 29.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libavcodec 60. 31.102 / 60. 31.102 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libavformat 60. 16.100 / 60. 16.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libavdevice 60. 3.100 / 60. 3.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libavfilter 9. 12.100 / 9. 12.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libswscale 7. 5.100 / 7. 5.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libswresample 4. 12.100 / 4. 12.100 2024-11-26T08:30:10.699Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] libpostproc 57. 3.100 / 57. 3.100 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Splitting the commandline. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-loglevel' ... matched as option 'loglevel' (set logging level) with argument 'level+debug'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-i' ... matched as output url with argument '/mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-n' ... matched as option 'n' (never overwrite output files) with argument '1'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-progress' ... matched as option 'progress' (write program-readable progress information) with argument 'tcp://127.0.0.1:46629'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-c:a' ... matched as option 'c' (codec name) with argument 'aac'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-b:a' ... matched as option 'b' (video bitrate (please use -b:v)) with argument '128000'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-ar' ... matched as option 'ar' (set audio sampling rate (in Hz)) with argument '48000'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-ac' ... matched as option 'ac' (set number of audio channels) with argument '2'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-c:v' ... matched as option 'c' (codec name) with argument 'libx264'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-crf' ... matched as AVOption 'crf' with argument '17'. 2024-11-26T08:30:10.699Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-vf' ... matched as option 'vf' (set video filters) with argument 'scale=1280:720'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-b:v' ... matched as option 'b' (video bitrate (please use -b:v)) with argument '2500000'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-maxrate' ... matched as AVOption 'maxrate' with argument '3000000'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-bufsize' ... matched as AVOption 'bufsize' with argument '1250000'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-tune' ... matched as AVOption 'tune' with argument 'film'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-profile:v' ... matched as option 'profile' (set profile) with argument 'baseline'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-map' ... matched as option 'map' (set input stream mapping) with argument '0:v:0'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '-c:s' ... matched as option 'c' (codec name) with argument 'mov_text'. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Reading option '/mnt/atlas/contents/100010260//ffmpeglog_Content-Profile-720p.mp4' ... matched as output url. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Finished splitting the commandline. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Parsing a group of options: global . 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option loglevel (set logging level) with argument level+debug. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option n (never overwrite output files) with argument 1. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option progress (write program-readable progress information) with argument tcp://127.0.0.1:46629. 2024-11-26T08:30:10.700Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [debug] No default whitelist set 2024-11-26T08:30:10.702Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [debug] Original list of addresses: 2024-11-26T08:30:10.702Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [debug] Address 127.0.0.1 port 46629 2024-11-26T08:30:10.702Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [debug] Interleaved list of addresses: 2024-11-26T08:30:10.702Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [debug] Address 127.0.0.1 port 46629 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [verbose] Starting connection attempt to 127.0.0.1 port 46629 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [tcp @ 0x55c8baa696c0] [verbose] Successfully connected to 127.0.0.1 port 46629 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Successfully parsed a group of options. 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Parsing a group of options: input url /mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv. 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Successfully parsed a group of options. 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Opening an input file: /mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv. 2024-11-26T08:30:10.703Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFormatContext @ 0x55c8baa71a00] [debug] Opening '/mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv' for reading 2024-11-26T08:30:10.708Z DEBUG 1 --- [ Runnable-0] c.github.kokorin.jaffree.net.TcpServer : Connection accepted, serving: 127.0.0.1:46629 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [file @ 0x55c8baa71f00] [debug] Setting default whitelist 'file,crypto,data' 2024-11-26T08:30:10.709Z DEBUG 1 --- [ Runnable-0] c.g.k.j.ffmpeg.FFmpegProgressReader : Reading encoding progress 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [debug] Format matroska,webm probed with size=2048 and score=100 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] st:0 removing common factor 1000000 from timebase 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] st:1 removing common factor 1000000 from timebase 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] st:2 removing common factor 1000000 from timebase 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] st:3 removing common factor 1000000 from timebase 2024-11-26T08:30:10.709Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] st:4 removing common factor 1000000 from timebase 2024-11-26T08:30:10.722Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [debug] Before avformat_find_stream_info() pos: 2327 bytes read:32768 seeks:0 nb_streams:5 2024-11-26T08:30:10.722Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] nal_unit_type: 7(SPS), nal_ref_idc: 3 2024-11-26T08:30:10.722Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] Decoding VUI 2024-11-26T08:30:10.742Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] nal_unit_type: 8(PPS), nal_ref_idc: 3 2024-11-26T08:30:10.742Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 128, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.746Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.746Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.747Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.747Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 64, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches: 2024-11-26T08:30:10.747Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448 2024-11-26T08:30:10.747Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 447 2024-11-26T08:30:10.747Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416 2024-11-26T08:30:10.748Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.748Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 128, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.748Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_asm_float_fma3 - type: fft_float, len: 64, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.794Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 256, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 128, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches: 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 447 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 256, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.795Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_asm_float_fma3 - type: fft_float, len: 128, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 64, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 32, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 2 matches: 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 512 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft32_asm_float_avx - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 64, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 64, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.796Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 32, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 2 matches: 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 512 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft32_asm_float_avx - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 64, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 120, inverse, mdct_float, flags: [aligned, out_of_place], found 6 matches: 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.797Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_pfa_15xM_inv_float_c - type: mdct_float, len: [30, ∞], factors[2]: [15, any], flags: [unaligned, out_of_place, inv_only], prio: 304 2024-11-26T08:30:10.798Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_pfa_5xM_inv_float_c - type: mdct_float, len: [10, ∞], factors[2]: [5, any], flags: [unaligned, out_of_place, inv_only], prio: 144 2024-11-26T08:30:10.799Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 4: mdct_pfa_3xM_inv_float_c - type: mdct_float, len: [6, ∞], factors[2]: [3, any], flags: [unaligned, out_of_place, inv_only], prio: 112 2024-11-26T08:30:10.801Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 5: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.801Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 6: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.802Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 60, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 1 matches: 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: [60, ∞], factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 655 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 4, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 1 matches: 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft4_fwd_asm_float_sse2 - type: fft_float, len: 4, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 352 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 120, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: 60, factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft4_fwd_asm_float_sse2 - type: fft_float, len: 4, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 128, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 64, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches: 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 447 2024-11-26T08:30:10.803Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 128, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_asm_float_fma3 - type: fft_float, len: 64, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 480, inverse, mdct_float, flags: [aligned, out_of_place], found 6 matches: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_pfa_15xM_inv_float_c - type: mdct_float, len: [30, ∞], factors[2]: [15, any], flags: [unaligned, out_of_place, inv_only], prio: 304 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_pfa_5xM_inv_float_c - type: mdct_float, len: [10, ∞], factors[2]: [5, any], flags: [unaligned, out_of_place, inv_only], prio: 144 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 4: mdct_pfa_3xM_inv_float_c - type: mdct_float, len: [6, ∞], factors[2]: [3, any], flags: [unaligned, out_of_place, inv_only], prio: 112 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 5: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 6: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 240, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 1 matches: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: [60, ∞], factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 655 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 16, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 2 matches: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft16_asm_float_fma3 - type: fft_float, len: 16, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 512 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft16_asm_float_avx - type: fft_float, len: 16, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 480, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: 240, factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft16_asm_float_fma3 - type: fft_float, len: 16, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 512, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 256, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches: 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 447 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416 2024-11-26T08:30:10.804Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 512, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_asm_float_fma3 - type: fft_float, len: 256, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 960, inverse, mdct_float, flags: [aligned, out_of_place], found 6 matches: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_pfa_15xM_inv_float_c - type: mdct_float, len: [30, ∞], factors[2]: [15, any], flags: [unaligned, out_of_place, inv_only], prio: 304 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_pfa_5xM_inv_float_c - type: mdct_float, len: [10, ∞], factors[2]: [5, any], flags: [unaligned, out_of_place, inv_only], prio: 144 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 4: mdct_pfa_3xM_inv_float_c - type: mdct_float, len: [6, ∞], factors[2]: [3, any], flags: [unaligned, out_of_place, inv_only], prio: 112 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 5: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 6: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 480, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 1 matches: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: [60, ∞], factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 655 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 32, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 2 matches: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 512 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft32_asm_float_avx - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 960, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_pfa_15xM_asm_float_avx2 - type: fft_float, len: 480, factors[2]: [15, 2], flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft32_asm_float_fma3 - type: fft_float, len: 32, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call] 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 1024, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 511 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 512, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 447 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.805Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_inv_float_avx2 - type: mdct_float, len: 1024, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only] 2024-11-26T08:30:10.806Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_asm_float_fma3 - type: fft_float, len: 512, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]2024-11-26T08:30:10.806Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 1024, forward, mdct_float, flags: [aligned, out_of_place], found 2 matches: 2024-11-26T08:30:10.806Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: mdct_fwd_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, fwd_only], prio: 96 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: mdct_naive_fwd_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, fwd_only], prio: -130976 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] For transform of length 512, forward, fft_float, flags: [aligned, inplace, preshuf], found 5 matches: 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 1: fft_sr_ns_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf], prio: 448 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 2: fft_sr_ns_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf], prio: 447 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 3: fft_sr_ns_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf], prio: 416 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 4: fft_pfa_ns_float_c - type: fft_float, len: [6, ∞], factors[2]: [7, 5, 3, 2, any], flags: [unaligned, inplace, out_of_place, preshuf], prio: 112 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] 5: fft512_ns_float_c - type: fft_float, len: 512, factor: 2, flags: [unaligned, inplace, out_of_place, preshuf], prio: 96 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Transform tree: 2024-11-26T08:30:10.815Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] mdct_fwd_float_c - type: mdct_float, len: 1024, factors[2]: [2, any], flags: [unaligned, out_of_place, fwd_only] 2024-11-26T08:30:10.818Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] fft_sr_ns_float_fma3 - type: fft_float, len: 512, factor: 2, flags: [aligned, inplace, out_of_place, preshuf] 2024-11-26T08:30:10.818Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] nal_unit_type: 7(SPS), nal_ref_idc: 3 2024-11-26T08:30:10.818Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] Decoding VUI 2024-11-26T08:30:10.819Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] nal_unit_type: 8(PPS), nal_ref_idc: 3 2024-11-26T08:30:10.819Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] nal_unit_type: 5(IDR), nal_ref_idc: 3 2024-11-26T08:30:10.819Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] Format yuv420p chosen by get_format(). 2024-11-26T08:30:10.834Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [verbose] Reinit context to 1792x1072, pix_fmt: yuv420p 2024-11-26T08:30:10.834Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baa77b40] [debug] no picture 2024-11-26T08:30:10.839Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [debug] first_dts 2168 not matching first dts NOPTS (pts 2126, duration 41) in the queue 2024-11-26T08:30:10.839Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [verbose] max_analyze_duration 5000000 reached at 5024000 microseconds st:1 2024-11-26T08:30:10.839Z WARN 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [warning] Could not find codec parameters for stream 4 (Video: mjpeg, 1 reference frame, none(pc, bt470bg/unknown/unknown, center), 2000x3000, 0/1): unspecified pixel format Consider increasing the value for the 'analyzeduration' (0) and 'probesize' (5000000) options 2024-11-26T08:30:10.839Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [matroska,webm @ 0x55c8baa71a00] [debug] After avformat_find_stream_info() pos: 1339207 bytes read:1343488 seeks:0 frames:472 2024-11-26T08:30:10.839Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Input #0, matroska,webm, from '/mnt/isilon/transcoded_contents/ffmpeglog_2121/short_lucky_luke.mkv': 2024-11-26T08:30:10.839Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.839Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : Lucky Luke - 1991 | Filmbol 2024-11-26T08:30:10.839Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] IMDB : tt0102351 2024-11-26T08:30:10.839Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] ENCODER : Lavf60.3.100 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Duration: 00:04:00.21, start: 0.000000, bitrate: 2816 kb/s 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:0(eng), 74, 1/1000: Video: h264 (High), 1 reference frame, yuv420p(progressive, left), 1792x1072 [SAR 1:1 DAR 112:67], 0/1, 24 fps, 24 tbr, 1k tbn (default) 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : 1080p 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] BPS : 2149944 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_FRAMES: 128854 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_BYTES : 1442859281 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_APP: mkvmerge v88.0 ('All I Know') 64-bit 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_DATE_UTC: 2024-11-15 19:18:14 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:04:00.209000000 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:1(tur), 159, 1/1000: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s (default) 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : Türkçe | www.filmbol.org 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] BPS : 192000 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_FRAMES: 167772 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_BYTES : 128848896 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_APP: mkvmerge v88.0 ('All I Know') 64-bit 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_DATE_UTC: 2024-11-15 19:18:14 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:04:00.006000000 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:2(eng), 239, 1/1000: Audio: aac (LC), 48000 Hz, stereo, fltp 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : Orijinal | www.filmbol.org 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] BPS : 94760 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_FRAMES: 251652 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_BYTES : 63591152 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_APP: mkvmerge v88.0 ('All I Know') 64-bit 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_DATE_UTC: 2024-11-15 19:18:14 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:04:00.020000000 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:3(tur), 0, 1/1000: Subtitle: subrip 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : Tam (Türkçe) 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] BPS : 37 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_FRAMES: 729 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_BYTES : 23658 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_APP: mkvmerge v88.0 ('All I Know') 64-bit 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_DATE_UTC: 2024-11-15 19:18:14 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:01:43.563000000 2024-11-26T08:30:10.840Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:4, 0, 1/1000: Video: mjpeg, 1 reference frame, none(pc, bt470bg/unknown/unknown, center), 2000x3000, 0/1, SAR 1:1 DAR 2:3, 1k tbr, 1k tbn 2024-11-26T08:30:10.841Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:10.841Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : autogenerated by Filmbol 2024-11-26T08:30:10.841Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] FILENAME : cover.jpg 2024-11-26T08:30:10.841Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] MIMETYPE : image/jpeg 2024-11-26T08:30:10.841Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:00:00.000000000 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Successfully opened the file. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Parsing a group of options: output url /mnt/atlas/contents/100010260//ffmpeglog_Content-Profile-720p.mp4. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option c:a (codec name) with argument aac. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option b:a (video bitrate (please use -b:v)) with argument 128000. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option ar (set audio sampling rate (in Hz)) with argument 48000. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option ac (set number of audio channels) with argument 2. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option c:v (codec name) with argument libx264. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option vf (set video filters) with argument scale=1280:720. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option b:v (video bitrate (please use -b:v)) with argument 2500000. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option profile:v (set profile) with argument baseline. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option map (set input stream mapping) with argument 0:v:0. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Applying option c:s (codec name) with argument mov_text. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Successfully parsed a group of options. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Opening an output file: /mnt/atlas/contents/100010260//ffmpeglog_Content-Profile-720p.mp4. 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [out#0/mp4 @ 0x55c8baa87040] [verbose] Adding streams from explicit maps... 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [vost#0:0/libx264 @ 0x55c8baadfe00] [verbose] Created video stream from input stream 0:0 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFilterGraph @ 0x55c8babb4540] [debug] Setting 'w' to value '1280' 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFilterGraph @ 0x55c8babb4540] [debug] Setting 'h' to value '720' 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [Parsed_scale_0 @ 0x55c8baae2140] [verbose] w:1280 h:720 flags:'' interl:0 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] detected 16 logical cores 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] nal_unit_type: 7(SPS), nal_ref_idc: 3 2024-11-26T08:30:10.841Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] Decoding VUI 2024-11-26T08:30:10.899Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] nal_unit_type: 8(PPS), nal_ref_idc: 3 2024-11-26T08:30:10.900Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [file @ 0x55c8baae2140] [debug] Setting default whitelist 'file,crypto,data' 2024-11-26T08:30:10.900Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [debug] Successfully opened the file. 2024-11-26T08:30:10.900Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream mapping: 2024-11-26T08:30:10.900Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:0 -> #0:0 (h264 (native) -> h264 (libx264)) 2024-11-26T08:30:10.935Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Press [q] to stop, [?] for help 2024-11-26T08:30:10.935Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] nal_unit_type: 5(IDR), nal_ref_idc: 3 2024-11-26T08:30:10.935Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] Format yuv420p chosen by get_format(). 2024-11-26T08:30:10.946Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [verbose] Reinit context to 1792x1072, pix_fmt: yuv420p 2024-11-26T08:30:10.989Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] no picture 2024-11-26T08:30:10.994Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bac227c0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.011Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bac227c0] [debug] no picture 2024-11-26T08:30:11.014Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baae3cc0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.041Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8babbb6c0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.041Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8babb3d00] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.092Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bab51d80] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.108Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bab65c40] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.108Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bab79b00] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.109Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bab8d9c0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.114Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baba1ac0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.119Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb56cc40] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.128Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb63ba00] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.133Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb70a880] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.137Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb7d9700] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.147Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb8a8580] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.202Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bb977400] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 2 2024-11-26T08:30:11.206Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8baad3e00] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.206Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFilterGraph @ 0x55c8babb4540] [debug] Setting 'w' to value '1280' 2024-11-26T08:30:11.206Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFilterGraph @ 0x55c8babb4540] [debug] Setting 'h' to value '720' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [h264 @ 0x55c8bac227c0] [debug] nal_unit_type: 1(Coded slice of a non-IDR picture), nal_ref_idc: 0 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [Parsed_scale_0 @ 0x55c8babaa0c0] [verbose] w:1280 h:720 flags:'' interl:0 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [debug] Setting 'video_size' to value '1792x1072' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [debug] Setting 'pix_fmt' to value '0' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [debug] Setting 'time_base' to value '1/1000' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [debug] Setting 'pixel_aspect' to value '1/1' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [debug] Setting 'frame_rate' to value '24/1' 2024-11-26T08:30:11.249Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [graph 0 input from stream 0:0 @ 0x55c8bab12800] [verbose] w:1792 h:1072 pixfmt:yuv420p tb:1/1000 fr:24/1 sar:1/1 2024-11-26T08:30:11.250Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [format @ 0x55c8bab07e00] [debug] Setting 'pix_fmts' to value 'yuv420p|yuvj420p|yuv422p|yuvj422p|yuv444p|yuvj444p|nv12|nv16|nv21|yuv420p10le|yuv422p10le|yuv444p10le|nv20le|gray|gray10le' 2024-11-26T08:30:11.302Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [AVFilterGraph @ 0x55c8babb4540] [debug] query_formats: 4 queried, 3 merged, 0 already done, 0 delayed 2024-11-26T08:30:11.310Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [Parsed_scale_0 @ 0x55c8babaa0c0] [verbose] w:1792 h:1072 fmt:yuv420p sar:1/1 -> w:1280 h:720 fmt:yuv420p sar:63/67 flags:0x00000004 2024-11-26T08:30:11.317Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [vost#0:0/libx264 @ 0x55c8baadfe00] [verbose] *** 51 dup! 2024-11-26T08:30:11.317Z DEBUG 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [libx264 @ 0x55c8bad315c0] [debug] using mv_range_thread = 24 2024-11-26T08:30:11.321Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [libx264 @ 0x55c8bad315c0] [info] using SAR=63/67 2024-11-26T08:30:11.408Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [libx264 @ 0x55c8bad315c0] [info] using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2 AVX512 2024-11-26T08:30:11.419Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [libx264 @ 0x55c8bad315c0] [info] profile Constrained Baseline, level 3.1, 4:2:0, 8-bit 2024-11-26T08:30:11.419Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [libx264 @ 0x55c8bad315c0] [info] 264 - core 164 r3108 31e19f9 - H.264/MPEG-4 AVC codec - Copyleft 2003-2023 - http://www.videolan.org/x264.html - options: cabac=0 ref=3 deblock=1:-1:-1 analyse=0x1:0x111 me=hex subme=7 psy=1 psy_rd=1.00:0.15 mixed_ref=1 me_range=16 chroma_me=1 trellis=1 8x8dct=0 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=-3 threads=22 lookahead_threads=3 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=0 weightp=0 keyint=250 keyint_min=24 scenecut=40 intra_refresh=0 rc_lookahead=40 rc=crf mbtree=1 crf=17.0 qcomp=0.60 qpmin=0 qpmax=69 qpstep=4 vbv_maxrate=3000 vbv_bufsize=1250 crf_max=0.0 nal_hrd=none filler=0 ip_ratio=1.40 aq=1:1.00 2024-11-26T08:30:11.419Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Output #0, mp4, to '/mnt/atlas/contents/100010260//ffmpeglog_Content-Profile-720p.mp4': 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : Lucky Luke - 1991 | Filmbol 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] IMDB : tt0102351 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] encoder : Lavf60.16.100 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Stream #0:0(eng), 0, 1/12288: Video: h264, 1 reference frame (avc1 / 0x31637661), yuv420p(tv, progressive, left), 1280x720 (0x0) [SAR 63:67 DAR 112:67], 0/1, q=2-31, 2500 kb/s, 24 fps, 12288 tbn (default) 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Metadata: 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] title : 1080p 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] BPS : 2149944 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_FRAMES: 128854 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] NUMBER_OF_BYTES : 1442859281 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_APP: mkvmerge v88.0 ('All I Know') 64-bit 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_WRITING_DATE_UTC: 2024-11-15 19:18:14 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] _STATISTICS_TAGS: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] DURATION : 00:04:00.209000000 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] encoder : Lavc60.31.102 libx264 2024-11-26T08:30:11.420Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] Side data: 2024-11-26T08:30:12.215Z INFO 1 --- [ StdErr] c.g.k.jaffree.process.BaseStdReader : [info] cpb: bitrate max/min/avg: 3000000/0/2500000 buffer size: 1250000 vbv_delay: N/A 2024-11-26T08:30:12.216Z ERROR 1 --- [ Runnable-0] c.s.a.e.s.i.EncodeJobServiceImp : Progress: FFmpegProgress{frame=0, fps=0.0, q=0.0, size=0, timeMicros=null, dup=51, drop=0, bitrate=null, speed=null} 2024-11-26T08:30:12.216Z DEBUG 1 --- [ Runnable-0] c.g.kokorin.jaffree.process.Executor : Exception in thread Runnable-0, collecting for later report. Message: TCP negotiation failed 2024-11-26T08:30:12.216Z WARN 1 --- [ Runnable-0] c.g.kokorin.jaffree.process.Executor : Interrupting starter thread (Thread-11) because of exception: TCP negotiation failed 2024-11-26T08:30:12.216Z DEBUG 1 --- [ Runnable-0] c.g.kokorin.jaffree.process.Executor : Runnable-0 thread has finished 2024-11-26T08:30:12.217Z WARN 1 --- [ Thread-11] c.g.k.jaffree.process.ProcessHandler : Process has been interrupted 2024-11-26T08:30:12.217Z DEBUG 1 --- [ Thread-11] c.g.k.jaffree.ffmpeg.FFmpegStopper : Stopping ffmpeg by sending "qq" to StdIn 2024-11-26T08:30:12.217Z DEBUG 1 --- [ Thread-11] c.g.kokorin.jaffree.process.Executor : Stopping execution 2024-11-26T08:30:12.217Z WARN 1 --- [ Thread-11] c.g.kokorin.jaffree.process.Executor : Interrupting ALIVE thread: StdErr 2024-11-26T08:30:12.217Z WARN 1 --- [ Thread-11] c.g.kokorin.jaffree.process.Executor : Interrupting ALIVE thread: StdOut 2024-11-26T08:30:12.217Z ERROR 1 --- [ Thread-11] c.s.a.e.s.i.EncodeJobServiceImp : Failed to execute, was interrupted 2024-11-26T08:30:12.222Z DEBUG 1 --- [ StdErr] c.g.kokorin.jaffree.process.Executor : Exception in thread StdErr, collecting for later report. Message: Read failed 2024-11-26T08:30:12.222Z DEBUG 1 --- [ StdErr] c.g.kokorin.jaffree.process.Executor : StdErr thread has finished