X265 2.8 seems much slower

Support for HandBrake on Linux, Solaris, and other Unix-like platforms
Forum rules
An Activity Log is required for support requests. Please read How-to get an activity log? for details on how and why this should be provided.
Post Reply
Navvie
Posts: 46
Joined: Sun Mar 27, 2011 10:19 pm

X265 2.8 seems much slower

Post by Navvie » Tue May 29, 2018 8:46 pm

Description of problem or question:
X265 2.8 seems much slower than 2.6.
I'm currently encoding Supernatural Season 1 from my Blu-Ray rips and swapped to the nightly with X265 2.8 and my encode speed has dropped from 5 - 6fps to 1 - 2 fps. Running with NLmeans ultralight to take the edge off the worst of the noise.

Admittedly I'm just running through the episodes in order and the latter episodes might be much more difficult, but I was consistently getting the 5 - 6 fps with X265 2.6, I upgraded mid queue and on resuming the queue I'm now getting 1 - 2 fps.



Steps to reproduce the problem (If Applicable):




HandBrake version (e.g., 1.0.0):
20180526224748-1ad2285-master (2018052701)
20180528010119-fd2241a-master (2018052801)



Operating system and version (e.g., Ubuntu 16.04 LTS, macOS 10.13 High Sierra, Windows 10 Creators Update):
Linux Mint 18.3 Cinnamon 64-bit



HandBrake Activity Log ***required*** (see How-to get an activity log)

The episodes on season 1 discs are all in one play list, I've used mkvtoolnix to split into episodes.

Log of Season 1, Disc 3, track 01 003 with X265 2.6

Code: Select all

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
[16:18:36] gtkgui: Modified Custom Preset: /General/navvie's X265 v2
[16:18:36] 1 job(s) to process
[16:18:36] json job:
{
    "Audio": {
        "AudioList": [
            {
                "Bitrate": 0,
                "DRC": 0.0,
                "Encoder": "copy:ac3",
                "Mixdown": "none",
                "PresetEncoder": "copy",
                "Quality": -3.0,
                "Samplerate": 0,
                "Track": 0
            }
        ],
        "CopyMask": [
            "copy:mp3",
            "copy:aac",
            "copy:ac3",
            "copy:dts",
            "copy:dtshd",
            "copy:eac3",
            "copy:flac",
            "copy:truehd"
        ],
        "FallbackEncoder": "av_aac"
    },
    "Destination": {
        "AlignAVStart": false,
        "ChapterList": [
            {
                "Name": "Chapter 1"
            },
            {
                "Name": "Chapter 2"
            },
            {
                "Name": "Chapter 3"
            },
            {
                "Name": "Chapter 4"
            },
            {
                "Name": "Chapter 5"
            },
            {
                "Name": "Chapter 6"
            },
            {
                "Name": "Chapter 7"
            },
            {
                "Name": "Chapter 8"
            }
        ],
        "ChapterMarkers": true,
        "File": "/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv",
        "InlineParameterSets": false,
        "Mp4Options": {
            "IpodAtom": false,
            "Mp4Optimize": false
        },
        "Mux": "mkv"
    },
    "Filters": {
        "FilterList": [
            {
                "ID": 6,
                "Settings": {
                    "mode": 0
                }
            },
            {
                "ID": 9,
                "Settings": {
                    "cb-frame-count": 2,
                    "cb-origin-tune": 1.0,
                    "cb-patch-size": 7,
                    "cb-prefilter": 0,
                    "cb-range": 3,
                    "cb-strength": 1.5,
                    "y-frame-count": 2,
                    "y-origin-tune": 1.0,
                    "y-patch-size": 7,
                    "y-prefilter": 0,
                    "y-range": 3,
                    "y-strength": 1.5
                }
            },
            {
                "ID": 11,
                "Settings": {
                    "crop-bottom": 0,
                    "crop-left": 2,
                    "crop-right": 2,
                    "crop-top": 0,
                    "height": 1080,
                    "width": 1916
                }
            }
        ]
    },
    "Metadata": {
        "Name": "Supernatural Season 1 Disc 3"
    },
    "PAR": {
        "Den": 1,
        "Num": 1
    },
    "SequenceID": 0,
    "Source": {
        "Angle": 0,
        "Path": "/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv",
        "Range": {
            "End": 8,
            "Start": 1,
            "Type": "chapter"
        },
        "Title": 9
    },
    "Subtitle": {
        "Search": {
            "Burn": false,
            "Default": true,
            "Enable": true,
            "Forced": true
        },
        "SubtitleList": [
            {
                "Burn": false,
                "Default": false,
                "Forced": false,
                "Track": 0
            }
        ]
    },
    "Video": {
        "ColorMatrixCode": 0,
        "Encoder": "x265",
        "Level": "auto",
        "Options": "strong-intra-smoothing=0:rect=0:deblock=-2,-2",
        "Preset": "slow",
        "Profile": "main",
        "QSV": {
            "AsyncDepth": 4,
            "Decode": false
        },
        "Quality": 19.0,
        "Tune": "",
        "Turbo": false,
        "TwoPass": false
    }
}
[16:18:36] CPU: Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
[16:18:36]  - Intel microarchitecture Haswell
[16:18:36]  - logical processor count: 8
[16:18:36] hb_scan: path=/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv, title_index=9
udfread ERROR: ECMA 167 Volume Recognition failed
disc.c:323: failed opening UDF image /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv
disc.c:424: error opening file BDMV/index.bdmv
disc.c:424: error opening file BDMV/BACKUP/index.bdmv
[16:18:36] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[16:18:36] dvd: not a dvd - trying as a stream/file instead
Input #0, matroska,webm, from '/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv':
  Metadata:
    title           : Supernatural Season 1 Disc 3
  Duration: 00:43:19.64, start: 0.000000, bitrate: N/A
    Chapter #0:0: start 0.000000, end 0.000458
    Metadata:
      title           : Chapter 16
    Chapter #0:1: start 0.000458, end 35.577667
    Metadata:
      title           : Chapter 17
    Chapter #0:2: start 35.577667, end 428.720417
    Metadata:
      title           : Chapter 18
    Chapter #0:3: start 428.720417, end 1155.446417
    Metadata:
      title           : Chapter 19
    Chapter #0:4: start 1155.446417, end 1899.189417
    Metadata:
      title           : Chapter 20
    Chapter #0:5: start 1899.189417, end 2337.627417
    Metadata:
      title           : Chapter 21
    Chapter #0:6: start 2337.627417, end 2557.680583
    Metadata:
      title           : Chapter 22
    Chapter #0:7: start 2557.680583, end 2599.613000
    Metadata:
      title           : Chapter 23
    Stream #0:0(eng): Video: vc1 (Advanced) [WVC1 / 0x31435657]
      yuv420p, bt709/bt709/bt709
      1920x1080 [PAR 1:1 DAR 16:9], PAR 1:1 DAR 16:9
      23.98 fps, 1k tbn
    Metadata:
      BPS-eng         : 17020720
      DURATION-eng    : 00:43:19.639000000
      NUMBER_OF_FRAMES-eng: 62329
      NUMBER_OF_BYTES-eng: 5530966102
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
    Stream #0:1(eng): Audio: ac3
      48000 Hz, 5.1, fltp, 640 kb/s (default)
    Metadata:
      title           : Surround 5.1
      BPS-eng         : 640000
      DURATION-eng    : 00:43:19.616000000
      NUMBER_OF_FRAMES-eng: 81238
      NUMBER_OF_BYTES-eng: 207969280
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
    Stream #0:2(eng): Subtitle: hdmv_pgs_subtitle
    Metadata:
      BPS-eng         : 23584
      DURATION-eng    : 00:43:15.030000000
      NUMBER_OF_FRAMES-eng: 904
      NUMBER_OF_BYTES-eng: 7650386
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
[16:18:36] scan: decoding previews for title 9
[16:18:36] scan: audio 0x1: ac3, rate=48000Hz, bitrate=640000 English (AC3) (5.1 ch)
[16:18:36] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/2/2, aspect 16:9, PAR 1:1
[16:18:36] libhb: scan thread found 1 valid title(s)
[16:18:36] starting job
[16:18:36] NLMeans using SSE2 optimizations
[16:18:36] NLMeans thread started for segment 0
[16:18:36] NLMeans thread started for segment 1
[16:18:36] NLMeans thread started for segment 2
[16:18:36] NLMeans thread started for segment 3
[16:18:36] NLMeans thread started for segment 4
[16:18:36] NLMeans thread started for segment 5
[16:18:36] job configuration:
[16:18:36]  * source
[16:18:36]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv
[16:18:36] NLMeans thread started for segment 7
[16:18:36] NLMeans thread started for segment 6
[16:18:36]    + title 9, chapter(s) 1 to 8
[16:18:36]    + container: matroska,webm
[16:18:36]  * destination
[16:18:36]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv
[16:18:36]    + container: Matroska (libavformat)
[16:18:36]      + chapter markers
[16:18:36]  * video track
[16:18:36]    + decoder: vc1
[16:18:36]    + filters
[16:18:36]      + Framerate Shaper (mode=0)
[16:18:36]        + frame rate: same as source (around 23.976 fps)
[16:18:36]      + Denoise (nlmeans) (y-strength=1.5:y-origin-tune=1:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=1.5:cb-origin-tune=1:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[16:18:36]      + Crop and Scale (width=1916:height=1080:crop-top=0:crop-bottom=0:crop-left=2:crop-right=2)
[16:18:36]        + source: 1920 * 1080, crop (0/0/2/2): 1916 * 1080, scale: 1916 * 1080
[16:18:36]    + Output geometry
[16:18:36]      + storage dimensions: 1916 x 1080
[16:18:36]      + pixel aspect ratio: 1 : 1
[16:18:36]      + display dimensions: 1916 x 1080
[16:18:36]  * Foreign Audio Search: Passthrough, Forced Only, Default
[16:18:36]    + subtitle, English [PGS] (track 0, id 0x2, Picture)
[16:18:36] sync: expecting 62329 video frames
[16:18:47] reader: done. 1 scr changes
[16:18:47] work: average encoding speed for job is 0.000000 fps
[16:18:47] vfr: lost time: 0 (0 frames)
[16:18:47] vfr: gained time: 0 (0 frames) (0 not accounted for)
[16:18:47] vc1-decoder done: 0 frames, 0 decoder errors
[16:18:47] sync: got 0 frames, 62329 expected
[16:18:47] Subtitle track 0 (id 0x2) 'English [PGS]': 452 hits (0 forced)
[16:18:47] No candidate detected during subtitle scan
[16:18:47] starting job
[16:18:47] NLMeans using SSE2 optimizations
[16:18:47] NLMeans thread started for segment 1
[16:18:47] NLMeans thread started for segment 0
[16:18:47] NLMeans thread started for segment 2
[16:18:47] NLMeans thread started for segment 3
[16:18:47] NLMeans thread started for segment 4
[16:18:47] NLMeans thread started for segment 5
[16:18:47] NLMeans thread started for segment 7
[16:18:47] job configuration:
[16:18:47] NLMeans thread started for segment 6
[16:18:47]  * source
[16:18:47]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv
[16:18:47]    + title 9, chapter(s) 1 to 8
[16:18:47]    + container: matroska,webm
[16:18:47]  * destination
[16:18:47]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-003.mkv
[16:18:47]    + container: Matroska (libavformat)
[16:18:47]      + chapter markers
[16:18:47]  * video track
[16:18:47]    + decoder: vc1
[16:18:47]    + filters
[16:18:47]      + Framerate Shaper (mode=0)
[16:18:47]        + frame rate: same as source (around 23.976 fps)
[16:18:47]      + Denoise (nlmeans) (y-strength=1.5:y-origin-tune=1:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=1.5:cb-origin-tune=1:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[16:18:47]      + Crop and Scale (width=1916:height=1080:crop-top=0:crop-bottom=0:crop-left=2:crop-right=2)
[16:18:47]        + source: 1920 * 1080, crop (0/0/2/2): 1916 * 1080, scale: 1916 * 1080
[16:18:47]    + Output geometry
[16:18:47]      + storage dimensions: 1916 x 1080
[16:18:47]      + pixel aspect ratio: 1 : 1
[16:18:47]      + display dimensions: 1916 x 1080
[16:18:47]    + encoder: H.265 (libx265)
[16:18:47]      + preset:  slow
[16:18:47]      + options: strong-intra-smoothing=0:rect=0:deblock=-2,-2
[16:18:47]      + profile: main
[16:18:47]      + quality: 19.00 (RF)
[16:18:47]  * subtitle track 1, English [PGS] (track 0, id 0x2, Picture) -> Passthrough
[16:18:47]  * audio track 1
[16:18:47]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x1)
[16:18:47]      + bitrate: 640 kbps, samplerate: 48000 Hz
[16:18:47]    + AC3 Passthru
[16:18:47] sync: expecting 62329 video frames
x265 [info]: HEVC encoder version 2.6
x265 [info]: build info [Linux][GCC 5.4.0][64 bit] 8bit+10bit+12bit
x265 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x265 [info]: Main profile, Level-4 (Main tier)
x265 [info]: Thread pool created using 8 threads
x265 [info]: Slices                              : 1
x265 [info]: frame threads / pool features       : 3 / wpp(17 rows)
x265 [info]: Coding QT: max CU size, min CU size : 64 / 8
x265 [info]: Residual QT: max TU size, max depth : 32 / 1 inter / 1 intra
x265 [info]: ME / range / subpel / merge         : star / 57 / 3 / 3
x265 [info]: Keyframe min / max / scenecut / bias: 24 / 240 / 40 / 5.00
x265 [info]: Lookahead / bframes / badapt        : 25 / 4 / 2
x265 [info]: b-pyramid / weightp / weightb       : 1 / 1 / 0
x265 [info]: References / ref-limit  cu / depth  : 4 / on / on
x265 [info]: AQ: mode / str / qg-size / cu-tree  : 1 / 1.0 / 32 / 1
x265 [info]: Rate Control / qCompress            : CRF-19.0 / 0.60
x265 [info]: tools: limit-modes rd=4 psy-rd=2.00 rdoq=2 psy-rdoq=1.00 rskip
x265 [info]: tools: signhide tmvp lslices=4 deblock(tC=-2:B=-2) sao
[16:18:47] sync: first pts video is 0
[16:18:47] sync: "Chapter 1" (1) at frame 1 time 0
[16:18:47] sync: first pts audio 0x1 is 2610
[16:18:47] sync: "Chapter 2" (2) at frame 2 time 3753
[16:18:49] sync: first pts subtitle 0x2 is 217800
[16:20:45] sync: "Chapter 3" (3) at frame 854 time 3201948
[16:45:01] sync: "Chapter 4" (4) at frame 10281 time 38588550
[17:29:30] sync: "Chapter 5" (5) at frame 27705 time 103993890
[18:22:46] sync: "Chapter 6" (6) at frame 45537 time 170930760
[18:51:06] sync: "Chapter 7" (7) at frame 56049 time 210390180
[19:03:46] sync: "Chapter 8" (8) at frame 61324 time 230191211
[19:04:55] reader: done. 1 scr changes
[19:05:07] work: average encoding speed for job is 6.245564 fps
[19:05:07] vfr: lost time: 0 (0 frames)
[19:05:07] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:05:07] ac3-decoder done: 81238 frames, 0 decoder errors
[19:05:07] vc1-decoder done: 62329 frames, 0 decoder errors
[19:05:07] sync: got 62329 frames, 62329 expected
[19:05:07] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x265 [info]: frame I:    773, Avg QP:16.53  kb/s: 16016.86
x265 [info]: frame P:  14654, Avg QP:17.97  kb/s: 9767.31 
x265 [info]: frame B:  46902, Avg QP:21.75  kb/s: 3424.84 
x265 [info]: Weighted P-Frames: Y:4.3% UV:2.1%
x265 [info]: consecutive B-frames: 8.7% 4.5% 5.1% 37.5% 44.2% 

encoded 62329 frames in 9979.84s (6.25 fps), 5072.16 kb/s, Avg QP:20.80
[19:05:07] mux: track 0, 62329 frames, 1648473166 bytes, 5072.85 kbps, fifo 2048
[19:05:07] mux: track 1, 81238 frames, 207969280 bytes, 639.98 kbps, fifo 2048
[19:05:07] mux: track 2, 904 frames, 7650386 bytes, 23.54 kbps, fifo 32
[19:05:07] libhb: work result = 0
Log of Season 1, Disc 3, track 01 004 with X265 2.8. Same settings. Stopped the queue to upgrade and carried on once the new version was installed.

Code: Select all

Handbrake Version: 20180528010119-fd2241a-master (2018052801)
[21:27:01] gtkgui: Modified Custom Preset: /General/navvie's X265 v2
[21:27:01] 1 job(s) to process
[21:27:01] json job:
{
    "Audio": {
        "AudioList": [
            {
                "Bitrate": 0,
                "DRC": 0.0,
                "Encoder": "copy:ac3",
                "Mixdown": "none",
                "PresetEncoder": "copy",
                "Quality": -3.0,
                "Samplerate": 0,
                "Track": 0
            }
        ],
        "CopyMask": [
            "copy:mp3",
            "copy:aac",
            "copy:ac3",
            "copy:dts",
            "copy:dtshd",
            "copy:eac3",
            "copy:flac",
            "copy:truehd"
        ],
        "FallbackEncoder": "av_aac"
    },
    "Destination": {
        "AlignAVStart": false,
        "ChapterList": [
            {
                "Name": "Chapter 1"
            },
            {
                "Name": "Chapter 2"
            },
            {
                "Name": "Chapter 3"
            },
            {
                "Name": "Chapter 4"
            },
            {
                "Name": "Chapter 5"
            },
            {
                "Name": "Chapter 6"
            },
            {
                "Name": "Chapter 7"
            },
            {
                "Name": "Chapter 8"
            },
            {
                "Name": "Chapter 9"
            }
        ],
        "ChapterMarkers": true,
        "File": "/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv",
        "InlineParameterSets": false,
        "Mp4Options": {
            "IpodAtom": false,
            "Mp4Optimize": false
        },
        "Mux": "mkv"
    },
    "Filters": {
        "FilterList": [
            {
                "ID": 6,
                "Settings": {
                    "mode": 0
                }
            },
            {
                "ID": 9,
                "Settings": {
                    "cb-frame-count": 2,
                    "cb-origin-tune": 1.0,
                    "cb-patch-size": 7,
                    "cb-prefilter": 0,
                    "cb-range": 3,
                    "cb-strength": 1.5,
                    "y-frame-count": 2,
                    "y-origin-tune": 1.0,
                    "y-patch-size": 7,
                    "y-prefilter": 0,
                    "y-range": 3,
                    "y-strength": 1.5
                }
            },
            {
                "ID": 11,
                "Settings": {
                    "crop-bottom": 0,
                    "crop-left": 2,
                    "crop-right": 2,
                    "crop-top": 0,
                    "height": 1080,
                    "width": 1916
                }
            }
        ]
    },
    "Metadata": {
        "Name": "Supernatural Season 1 Disc 3"
    },
    "PAR": {
        "Den": 1,
        "Num": 1
    },
    "SequenceID": 0,
    "Source": {
        "Angle": 0,
        "Path": "/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv",
        "Range": {
            "End": 9,
            "Start": 1,
            "Type": "chapter"
        },
        "Title": 1
    },
    "Subtitle": {
        "Search": {
            "Burn": false,
            "Default": true,
            "Enable": true,
            "Forced": true
        },
        "SubtitleList": [
            {
                "Burn": false,
                "Default": false,
                "Forced": false,
                "Track": 0
            }
        ]
    },
    "Video": {
        "ColorMatrixCode": 0,
        "Encoder": "x265",
        "Level": "auto",
        "Options": "strong-intra-smoothing=0:rect=0:deblock=-2,-2",
        "Preset": "slow",
        "Profile": "main",
        "QSV": {
            "AsyncDepth": 4,
            "Decode": false
        },
        "Quality": 19.0,
        "Tune": "",
        "Turbo": false,
        "TwoPass": false
    }
}
[21:27:01] CPU: Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
[21:27:01]  - Intel microarchitecture Haswell
[21:27:01]  - logical processor count: 8
[21:27:01] hb_scan: path=/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
disc.c:323: failed opening UDF image /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv
disc.c:424: error opening file BDMV/index.bdmv
disc.c:424: error opening file BDMV/BACKUP/index.bdmv
[21:27:01] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[21:27:01] dvd: not a dvd - trying as a stream/file instead
Input #0, matroska,webm, from '/media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv':
  Metadata:
    title           : Supernatural Season 1 Disc 3
  Duration: 00:41:46.56, start: 0.000000, bitrate: N/A
    Chapter #0:0: start 0.000000, end 0.000167
    Metadata:
      title           : Chapter 23
    Chapter #0:1: start 0.000167, end 36.536667
    Metadata:
      title           : Chapter 24
    Chapter #0:2: start 36.536667, end 229.729667
    Metadata:
      title           : Chapter 25
    Chapter #0:3: start 229.729667, end 588.504750
    Metadata:
      title           : Chapter 26
    Chapter #0:4: start 588.504750, end 1236.527125
    Metadata:
      title           : Chapter 27
    Chapter #0:5: start 1236.527125, end 1666.790292
    Metadata:
      title           : Chapter 28
    Chapter #0:6: start 1666.790292, end 2210.416708
    Metadata:
      title           : Chapter 29
    Chapter #0:7: start 2210.416708, end 2464.170208
    Metadata:
      title           : Chapter 30
    Chapter #0:8: start 2464.170208, end 2506.534000
    Metadata:
      title           : Chapter 31
    Stream #0:0(eng): Video: vc1 (Advanced) [WVC1 / 0x31435657]
      yuv420p, bt709/bt709/bt709
      1920x1080 [PAR 1:1 DAR 16:9], PAR 1:1 DAR 16:9
      23.98 fps, 1k tbn
    Metadata:
      BPS-eng         : 17121801
      DURATION-eng    : 00:41:46.546000000
      NUMBER_OF_FRAMES-eng: 60097
      NUMBER_OF_BYTES-eng: 5364572941
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
    Stream #0:1(eng): Audio: ac3
      48000 Hz, 5.1, fltp, 640 kb/s (default)
    Metadata:
      title           : Surround 5.1
      BPS-eng         : 640000
      DURATION-eng    : 00:41:46.560000000
      NUMBER_OF_FRAMES-eng: 78330
      NUMBER_OF_BYTES-eng: 200524800
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
    Stream #0:2(eng): Subtitle: hdmv_pgs_subtitle
    Metadata:
      BPS-eng         : 29898
      DURATION-eng    : 00:41:41.103000000
      NUMBER_OF_FRAMES-eng: 924
      NUMBER_OF_BYTES-eng: 9347450
      _STATISTICS_WRITING_APP-eng: mkvmerge v23.0.0 ('The Bride Said No') 64-bit
      _STATISTICS_WRITING_DATE_UTC-eng: 2018-05-27 11:15:33
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES
[21:27:01] scan: decoding previews for title 1
[21:27:01] scan: audio 0x1: ac3, rate=48000Hz, bitrate=640000 English (AC3) (5.1 ch)
[21:27:01] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/2/2, aspect 16:9, PAR 1:1
[21:27:01] libhb: scan thread found 1 valid title(s)
[21:27:01] starting job
[21:27:01] NLMeans using SSE2 optimizations
[21:27:01] NLMeans thread started for segment 1
[21:27:01] NLMeans thread started for segment 0
[21:27:01] NLMeans thread started for segment 3
[21:27:01] NLMeans thread started for segment 4
[21:27:01] NLMeans thread started for segment 5
[21:27:01] NLMeans thread started for segment 6
[21:27:01] job configuration:
[21:27:01]  * source
[21:27:01]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv
[21:27:01]    + title 1, chapter(s) 1 to 9
[21:27:01]    + container: matroska,webm
[21:27:01]  * destination
[21:27:01]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv
[21:27:01]    + container: Matroska (libavformat)
[21:27:01] NLMeans thread started for segment 7
[21:27:01] NLMeans thread started for segment 2
[21:27:01]      + chapter markers
[21:27:01]  * video track
[21:27:01]    + decoder: vc1
[21:27:01]    + filters
[21:27:01]      + Framerate Shaper (mode=0)
[21:27:01]        + frame rate: same as source (around 23.976 fps)
[21:27:01]      + Denoise (nlmeans) (y-strength=1.5:y-origin-tune=1:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=1.5:cb-origin-tune=1:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[21:27:01]      + Crop and Scale (width=1916:height=1080:crop-top=0:crop-bottom=0:crop-left=2:crop-right=2)
[21:27:01]        + source: 1920 * 1080, crop (0/0/2/2): 1916 * 1080, scale: 1916 * 1080
[21:27:01]    + Output geometry
[21:27:01]      + storage dimensions: 1916 x 1080
[21:27:01]      + pixel aspect ratio: 1 : 1
[21:27:01]      + display dimensions: 1916 x 1080
[21:27:01]  * Foreign Audio Search: Passthrough, Forced Only, Default
[21:27:01]    + subtitle, English [PGS] (track 0, id 0x2, Picture)
[21:27:01] sync: expecting 60097 video frames
[21:27:12] reader: done. 1 scr changes
[21:27:12] work: average encoding speed for job is 0.000000 fps
[21:27:12] vfr: lost time: 0 (0 frames)
[21:27:12] vfr: gained time: 0 (0 frames) (0 not accounted for)
[21:27:12] vc1-decoder done: 0 frames, 0 decoder errors
[21:27:12] sync: got 0 frames, 60097 expected
[21:27:12] Subtitle track 0 (id 0x2) 'English [PGS]': 462 hits (0 forced)
[21:27:12] No candidate detected during subtitle scan
[21:27:12] starting job
[21:27:12] NLMeans using SSE2 optimizations
[21:27:12] NLMeans thread started for segment 1
[21:27:12] NLMeans thread started for segment 0
[21:27:12] NLMeans thread started for segment 3
[21:27:12] NLMeans thread started for segment 2
[21:27:12] NLMeans thread started for segment 4
[21:27:12] NLMeans thread started for segment 5
[21:27:12] NLMeans thread started for segment 6
[21:27:12] job configuration:
[21:27:12] NLMeans thread started for segment 7
[21:27:12]  * source
[21:27:12]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_2/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv
[21:27:12]    + title 1, chapter(s) 1 to 9
[21:27:12]    + container: matroska,webm
[21:27:12]  * destination
[21:27:12]    + /media/navvie/Scratch/MakeMKV/Supernatural_Season_1_Disc_3_t01 (1)-004.mkv
[21:27:12]    + container: Matroska (libavformat)
[21:27:12]      + chapter markers
[21:27:12]  * video track
[21:27:12]    + decoder: vc1
[21:27:12]    + filters
[21:27:12]      + Framerate Shaper (mode=0)
[21:27:12]        + frame rate: same as source (around 23.976 fps)
[21:27:12]      + Denoise (nlmeans) (y-strength=1.5:y-origin-tune=1:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=1.5:cb-origin-tune=1:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[21:27:12]      + Crop and Scale (width=1916:height=1080:crop-top=0:crop-bottom=0:crop-left=2:crop-right=2)
[21:27:12]        + source: 1920 * 1080, crop (0/0/2/2): 1916 * 1080, scale: 1916 * 1080
[21:27:12]    + Output geometry
[21:27:12]      + storage dimensions: 1916 x 1080
[21:27:12]      + pixel aspect ratio: 1 : 1
[21:27:12]      + display dimensions: 1916 x 1080
[21:27:12]    + encoder: H.265 (libx265)
[21:27:12]      + preset:  slow
[21:27:12]      + options: strong-intra-smoothing=0:rect=0:deblock=-2,-2
[21:27:12]      + profile: main
[21:27:12]      + quality: 19.00 (RF)
[21:27:12]  * subtitle track 1, English [PGS] (track 0, id 0x2, Picture) -> Passthrough
[21:27:12]  * audio track 1
[21:27:12]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x1)
[21:27:12]      + bitrate: 640 kbps, samplerate: 48000 Hz
[21:27:12]    + AC3 Passthru
[21:27:12] sync: expecting 60097 video frames
x265 [info]: HEVC encoder version 2.8
x265 [info]: build info [Linux][GCC 5.4.0][64 bit][noasm] 8bit+10bit+12bit
x265 [info]: using cpu capabilities: none!
x265 [info]: Main profile, Level-4 (Main tier)
x265 [info]: Thread pool created using 8 threads
x265 [info]: Slices                              : 1
x265 [info]: frame threads / pool features       : 3 / wpp(17 rows)
x265 [info]: Coding QT: max CU size, min CU size : 64 / 8
x265 [info]: Residual QT: max TU size, max depth : 32 / 1 inter / 1 intra
x265 [info]: ME / range / subpel / merge         : star / 57 / 3 / 3
x265 [info]: Keyframe min / max / scenecut / bias: 24 / 240 / 40 / 5.00
x265 [info]: Lookahead / bframes / badapt        : 25 / 4 / 2
x265 [info]: b-pyramid / weightp / weightb       : 1 / 1 / 0
x265 [info]: References / ref-limit  cu / depth  : 4 / on / on
x265 [info]: AQ: mode / str / qg-size / cu-tree  : 1 / 1.0 / 32 / 1
x265 [info]: Rate Control / qCompress            : CRF-19.0 / 0.60
x265 [info]: tools: limit-modes rd=4 psy-rd=2.00 rdoq=2 psy-rdoq=1.00 rskip
x265 [info]: tools: signhide tmvp lslices=4 deblock(tC=-2:B=-2) sao
[21:27:12] sync: first pts video is 0
[21:27:12] sync: "Chapter 1" (1) at frame 1 time 0
[21:27:12] sync: first pts audio 0x1 is 540
[21:27:12] sync: "Chapter 2" (2) at frame 2 time 3753
[21:27:14] sync: first pts subtitle 0x2 is 217710
[21:39:29] sync: "Chapter 3" (3) at frame 877 time 3288285
[22:51:27] sync: "Chapter 4" (4) at frame 5509 time 20675655
[01:00:24] sync: "Chapter 5" (5) at frame 14111 time 52965412
[04:36:40] sync: "Chapter 6" (6) at frame 29649 time 111291180
[06:56:10] sync: "Chapter 7" (7) at frame 39964 time 150011111
[09:39:31] sync: "Chapter 8" (8) at frame 52998 time 198937488
[11:12:48] sync: "Chapter 9" (9) at frame 59083 time 221779057
[11:15:45] reader: done. 1 scr changes
[11:16:15] work: average encoding speed for job is 1.207664 fps
[11:16:15] vfr: lost time: 0 (0 frames)
[11:16:15] vfr: gained time: 0 (0 frames) (0 not accounted for)
[11:16:16] ac3-decoder done: 78330 frames, 0 decoder errors
[11:16:16] vc1-decoder done: 60097 frames, 0 decoder errors
[11:16:16] sync: got 60097 frames, 60097 expected
[11:16:16] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x265 [info]: frame I:    691, Avg QP:16.57  kb/s: 15235.18
x265 [info]: frame P:  14025, Avg QP:18.09  kb/s: 9279.36 
x265 [info]: frame B:  45381, Avg QP:21.83  kb/s: 3559.75 
x265 [info]: Weighted P-Frames: Y:4.8% UV:3.2%
x265 [info]: consecutive B-frames: 7.6% 3.4% 4.9% 41.4% 42.8% 

encoded 60097 frames in 49743.83s (1.21 fps), 5028.80 kb/s, Avg QP:20.89
[11:16:16] mux: track 0, 60097 frames, 1575853699 bytes, 5029.48 kbps, fifo 2048
[11:16:16] mux: track 1, 78330 frames, 200524800 bytes, 639.99 kbps, fifo 2048
[11:16:16] mux: track 2, 924 frames, 9347450 bytes, 29.83 kbps, fifo 32
[11:16:16] libhb: work result = 0
Logs above aren't definitive, it's a small sample I know... but it immediately looked odd. So much so i stopped the encode, re scanned the sources and started again. If I was guessing I'd say nlmeans filter is no longer multi-threaded?

Logs sections below are from previous episodes.

Code: Select all

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[22:03:39] work: average encoding speed for job is 6.381943 fps

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[00:59:45] work: average encoding speed for job is 5.900156 fps

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[04:25:47] work: average encoding speed for job is 5.068912 fps

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[07:30:21] work: average encoding speed for job is 5.487878 fps

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[10:18:34] work: average encoding speed for job is 6.182196 fps

Handbrake Version: 20180518225020-08ebb00-master (2018051901)
x265 [info]: HEVC encoder version 2.6
[15:12:16] work: average encoding speed for job is 5.896956 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[19:21:03] work: average encoding speed for job is 7.848569 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[22:51:26] work: average encoding speed for job is 5.424125 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[01:49:33] work: average encoding speed for job is 5.786440 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[04:36:07] work: average encoding speed for job is 6.250100 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[07:38:57] work: average encoding speed for job is 5.606689 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[10:42:26] work: average encoding speed for job is 5.669411 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[13:20:03] work: average encoding speed for job is 6.185578 fps

Handbrake Version: 20180526224748-1ad2285-master (2018052701)
x265 [info]: HEVC encoder version 2.6
[16:18:36] work: average encoding speed for job is 5.778743 fps
The currently running encode is showing an average fps of 1.25 with 2hrs 28 minutes to go.

Once the queue is done, I'll re-run one of the previous episodes with the same settings with X265 2.8 and post both logs.

mduell
Veteran User
Posts: 6153
Joined: Sat Apr 21, 2007 8:54 pm

Re: X265 2.8 seems much slower

Post by mduell » Tue May 29, 2018 8:49 pm

Relatively uninteresting given the confounding variables.

Navvie
Posts: 46
Joined: Sun Mar 27, 2011 10:19 pm

Re: X265 2.8 seems much slower

Post by Navvie » Tue May 29, 2018 8:56 pm

Have I done something daft that explains why X265 2.8 is a third the speed of 2.6?

User avatar
s55
HandBrake Team
Posts: 9218
Joined: Sun Dec 24, 2006 1:05 pm

Re: X265 2.8 seems much slower

Post by s55 » Tue May 29, 2018 9:04 pm

O, Interesting, CPU Instructions are no longer available on your system.

x265 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
vs
x265 [info]: using cpu capabilities: none!

User avatar
s55
HandBrake Team
Posts: 9218
Joined: Sun Dec 24, 2006 1:05 pm

Re: X265 2.8 seems much slower

Post by s55 » Tue May 29, 2018 9:06 pm

Yeh, your builds configured with no-asm.

Where did you get this build from?

Navvie
Posts: 46
Joined: Sun Mar 27, 2011 10:19 pm

Re: X265 2.8 seems much slower

Post by Navvie » Tue May 29, 2018 9:08 pm

Ha. I looked at those logs a dozen times before posting. Missed that. Second pair of eyes and all...

User avatar
s55
HandBrake Team
Posts: 9218
Joined: Sun Dec 24, 2006 1:05 pm

Re: X265 2.8 seems much slower

Post by s55 » Tue May 29, 2018 9:10 pm

Hmm, ubuntu PPA exhibits the same. Will figure out why that's turned off.

Navvie
Posts: 46
Joined: Sun Mar 27, 2011 10:19 pm

Re: X265 2.8 seems much slower

Post by Navvie » Tue May 29, 2018 9:10 pm

I'm a Linux noob (Windows/FreeBSD background), I just followed the instructions on the nightly builds page to install for Linux.

User avatar
JohnAStebbins
HandBrake Team
Posts: 5214
Joined: Sat Feb 09, 2008 7:21 pm

Re: X265 2.8 seems much slower

Post by JohnAStebbins » Tue May 29, 2018 9:45 pm

The problem has been located. x265 versions > 2.6 now require nasm for assembly optimizations. The nightly build config didn't list nasm as a dependency, so it was getting built without nasm. Problem should be fixed in the next nightly build (which may be a day or 2 away for other unrelated reasons).

Post Reply