Encoding of ISO-Files failed in latest nightly

[c.s.g.]

Description of problem or question:
I have 2 ISO-Files. In each file I want to encode 6 MKV-Files.
Encoding the six MKV-files of ISO-file one works as expected. But after encoding the first MKV-file of ISO-File two successfully handbrake is closing when trying to encode the second MKV-file of ISO-file two. After restart of handbrake 5 MKV-Files remain in the encoding queue. First file is marked with an !. Encoding then the remaining 5 MKV-files of ISO-file two works as expected.

In earlier versions of handbrake I did not have any problem encoding up to 6 ISO-files with over all up to 45 MKV-files.

Steps to reproduce the problem (If Applicable):
see Description of problem or question:

HandBrake version (e.g., 1.0.0):
Nightly 20190527163102-6c4e488-master (2019053001)

Operating system and version (e.g., Ubuntu 16.04 LTS, macOS 10.13 High Sierra, Windows 10 Creators Update):
Microsoft Windows 10 Pro 1803 17134.799

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

Code: Select all

HandBrake Nightly 20190527163102-6c4e488-master (2019053001)
OS: Microsoft Windows NT 10.0.17134.0
CPU: Intel(R) Core(TM) i7 CPU         860  @ 2.80GHz
Ram: 16317 MB, 
GPU Information:
  AMD Radeon Pro WX 3100 - 24.20.13026.0
Screen: 2560x1440
Temp Dir: C:\Users\Cerstin und Stefan\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake Nightly
Data Dir: C:\Users\Cerstin und Stefan\AppData\Roaming\HandBrake\Nightly

-------------------------------------------

Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll

[c.s.g.]

With the offical release version 1.2.2 (2019022300) everything works as expected.

[c.s.g.]

Maybe I found the "error" by myself.
On Tab "Video" there is a combobox "Video Codec".
The release version 1.2.2 runs without any problem with the selection "H.264 (x264)" in that combobox.
In the newset nightly I have to select "H.264 (AMD VCE)" in order to encode the two ISO-files without any problems.
At least it runs once without any problem with the new setting .
So there must be a change in behaviour in the nightly which causes the error.

But ....
(1.) .... the new setting "H.264 (AMD VCE)" encodes twice as much frames per second (120 fps) as the old setting do (60fps).
(2,) .... the new generated MKV-files are about 50% bigger than the old ones.
(3.) .... time of encoding per MKV-file is halved.

If the quality of the new setting is as good as with the old setting i dont care.
But maybe someone could explain it to me anyway.

[Deleted User 11865]

(1.) .... the new setting "H.264 (AMD VCE)" encodes twice as much frames per second (120 fps) as the old setting do (60fps).
(2,) .... the new generated MKV-files are about 50% bigger than the old ones.
(3.) .... time of encoding per MKV-file is halved.

Just FYI, (1) and (3) mean the same thing

For the failed x264 encodes, we need to see a log (the log you provided was taken just after the application started, you need to grab the log after you start the x264 encode)

[c.s.g.]

Hi,

here is the log after an unwanted shutdown:

Code: Select all

HandBrake Nightly 20190527163102-6c4e488-master (2019053001)
OS: Microsoft Windows NT 10.0.17134.0
CPU: Intel(R) Core(TM) i7 CPU         860  @ 2.80GHz
Ram: 16317 MB, 
GPU Information:
  AMD Radeon Pro WX 3100 - 24.20.13026.0
Screen: 2560x1440
Temp Dir: C:\Users\Cerstin und Stefan\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake Nightly
Data Dir: C:\Users\Cerstin und Stefan\AppData\Roaming\HandBrake\Nightly

-------------------------------------------


# Starting Encode ...

[22:06:04] hb_init: starting libhb thread
[22:06:04] 1 job(s) to process
[22:06:04] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0.0,
        "Encoder": "copy",
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 1,
        "DitherMethod": 0
      },
      {
        "DRC": 0.0,
        "Encoder": "copy",
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      "copy:aac",
      "copy:ac3",
      "copy:dtshd",
      "copy:dts",
      "copy:eac3",
      "copy:flac",
      "copy:mp3",
      "copy:truehd"
    ],
    "FallbackEncoder": "ac3"
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      },
      {
        "Name": "Chapter 2"
      },
      {
        "Name": "Chapter 3"
      },
      {
        "Name": "Chapter 4"
      },
      {
        "Name": "Chapter 5"
      },
      {
        "Name": "Chapter 6"
      },
      {
        "Name": "Chapter 7"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": false,
    "File": "C:\\Users\\Cerstin und Stefan\\Videos\\HandBrake\\ISO_02_F1.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "mkv"
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 4,
        "Settings": {
          "mode": "7"
        }
      },
      {
        "ID": 3,
        "Settings": {
          "block-height": "16",
          "block-thresh": "40",
          "block-width": "16",
          "filter-mode": "2",
          "mode": "3",
          "motion-thresh": "1",
          "spatial-metric": "2",
          "spatial-thresh": "1"
        }
      },
      {
        "ID": 12,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "576",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "1"
        }
      }
    ]
  },
  "PAR": {
    "Num": 64,
    "Den": 45
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 7
    },
    "Title": 1,
    "Path": "C:\\Users\\Cerstin und Stefan\\Videos\\ISO\\ISO_02.iso"
  },
  "Subtitle": {
    "Search": {
      "Burn": true,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": [
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 2,
        "Offset": 0,
        "Track": 1
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 1,
        "Offset": 0,
        "Track": 0
      }
    ]
  },
  "Video": {
    "Encoder": "x264",
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "medium",
    "Profile": "main",
    "Quality": 19.0,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[22:06:04] CPU: Intel(R) Core(TM) i7 CPU         860  @ 2.80GHz
[22:06:04]  - logical processor count: 8
[22:06:04] Intel Quick Sync Video support: no
[22:06:04] hb_scan: path=C:\Users\Cerstin und Stefan\Videos\ISO\ISO_02.iso, title_index=1
src/libbluray/disc/disc.c:424: error opening file BDMV\index.bdmv
src/libbluray/disc/disc.c:424: error opening file BDMV\BACKUP\index.bdmv
[22:06:04] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 6.0.0
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: IS0_2
libdvdnav: DVD Serial Number: 4af29bb5
libdvdnav: DVD Title (Alternative):
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[22:06:04] scan: DVD has 14 title(s)
[22:06:04] scan: scanning title 1
[22:06:04] scan: opening IFO for VTS 1
[22:06:04] scan: duration is 00:39:00 (2340000 ms)
[22:06:04] pgc_id: 1, pgn: 1: pgc: 0000021B67993210
[22:06:04] scan: vts=1, ttn=1, cells=0->6, blocks=0->928384, 928385 blocks
[22:06:04] scan: checking audio 1
[22:06:04] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=1
[22:06:04] scan: checking audio 2
[22:06:04] scan: id=0x81bd, lang=Deutsch (AC3), 3cc=deu ext=1
[22:06:04] scan: checking audio 3
[22:06:04] scan: id=0x82bd, lang=Francais (AC3), 3cc=fra ext=1
[22:06:04] scan: checking subtitle 1
[22:06:04] scan: id=0x20bd, lang=English (Wide Screen) [VOBSUB], 3cc=eng ext=1
[22:06:04] scan: checking subtitle 2
[22:06:04] scan: id=0x21bd, lang=Deutsch (Wide Screen) [VOBSUB], 3cc=deu ext=1
[22:06:04] scan: checking subtitle 3
[22:06:04] scan: id=0x22bd, lang=Francais (Wide Screen) [VOBSUB], 3cc=fra ext=1
[22:06:04] scan: checking subtitle 4
[22:06:04] scan: id=0x23bd, lang=Nederlands (Wide Screen) [VOBSUB], 3cc=nld ext=1
[22:06:04] scan: title 1 has 7 chapters
[22:06:04] scan: chap 1 c=0->0, b=0->93909 (93910), 193320 ms
[22:06:04] scan: chap 2 c=1->1, b=93910->182535 (88626), 218200 ms
[22:06:04] scan: chap 3 c=2->2, b=182536->378637 (196102), 498160 ms
[22:06:04] scan: chap 4 c=3->3, b=378638->541231 (162594), 418440 ms
[22:06:04] scan: chap 5 c=4->4, b=541232->711808 (170577), 450680 ms
[22:06:04] scan: chap 6 c=5->5, b=711809->922037 (210229), 533920 ms
[22:06:04] scan: chap 7 c=6->6, b=922038->928384 (6347), 27280 ms
[22:06:04] scan: aspect = 16:9
[22:06:04] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:06:04] scan: title angle(s) 1
[22:06:04] scan: audio 0x80bd: ac3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch) (448 kbps)
[22:06:04] scan: audio 0x81bd: ac3, rate=48000Hz, bitrate=448000 Deutsch (AC3) (5.1 ch) (448 kbps)
[22:06:04] scan: audio 0x82bd: ac3, rate=48000Hz, bitrate=448000 Francais (AC3) (5.1 ch) (448 kbps)
[22:06:04] scan: 10 previews, 720x576, 25.000 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 64:45
[22:06:04] libhb: scan thread found 1 valid title(s)
[22:06:04] starting job
[22:06:04] job configuration:
[22:06:04]  * source
[22:06:04]    + C:\Users\Cerstin und Stefan\Videos\ISO\ISO_02.iso
[22:06:04]    + title 1, chapter(s) 1 to 7
[22:06:04]  * destination
[22:06:04]    + C:\Users\Cerstin und Stefan\Videos\HandBrake\ISO_02_F1.mkv
[22:06:04]    + container: Matroska (libavformat)
[22:06:04]      + chapter markers
[22:06:04]  * video track
[22:06:04]    + decoder: mpeg2video
[22:06:04]      + bitrate 200 kbps
[22:06:04]    + filters
[22:06:04]      + Comb Detect (mode=3:spatial-metric=2:motion-thresh=1:spatial-thresh=1:filter-mode=2:block-thresh=40:block-width=16:block-height=16)
[22:06:04]      + Decomb (mode=39)
[22:06:04]      + Framerate Shaper (mode=1)
[22:06:04]        + frame rate: 25.000 fps -> constant 25.000 fps
[22:06:04]      + Crop and Scale (width=720:height=576:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[22:06:04]        + source: 720 * 576, crop (0/0/0/0): 720 * 576, scale: 720 * 576
[22:06:04]    + Output geometry
[22:06:04]      + storage dimensions: 720 x 576
[22:06:04]      + pixel aspect ratio: 64 : 45
[22:06:04]      + display dimensions: 1024 x 576
[22:06:04]  * Foreign Audio Search: Render/Burn-in, Forced Only
[22:06:04]    + subtitle, Deutsch (Wide Screen) [VOBSUB] (track 1, id 0x21bd, Picture)
libdvdnav: Using dvdnav version 6.0.0
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: IS0_2
libdvdnav: DVD Serial Number: 4af29bb5
libdvdnav: DVD Title (Alternative):
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:06:04] sync: expecting 58500 video frames
[22:06:12] reader: done. 1 scr changes
[22:06:12] work: average encoding speed for job is 0.000000 fps
[22:06:12] comb detect: heavy 0 | light 0 | uncombed 0 | total 0
[22:06:12] decomb: deinterlaced 0 | blended 0 | unfiltered 0 | total 0
[22:06:12] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[22:06:12] vfr: lost time: 0 (0 frames)
[22:06:12] vfr: gained time: 0 (0 frames) (0 not accounted for)
[22:06:12] mpeg2video-decoder done: 0 frames, 1 decoder errors
[22:06:12] sync: got 0 frames, 58500 expected
[22:06:12] Subtitle track 1 (id 0x21bd) 'Deutsch (Wide Screen) [VOBSUB]': 688 hits (0 forced)
[22:06:12] No candidate detected during subtitle scan
[22:06:12] starting job
[22:06:12] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[22:06:12] Auto Passthru: fallback is AC3
[22:06:12] Auto Passthru: using AC3 Passthru for track 1
[22:06:12] Auto Passthru: using AC3 Passthru for track 2
[22:06:12] job configuration:
[22:06:12]  * source
[22:06:12]    + C:\Users\Cerstin und Stefan\Videos\ISO\ISO_02.iso
[22:06:12]    + title 1, chapter(s) 1 to 7
[22:06:12]  * destination
[22:06:12]    + C:\Users\Cerstin und Stefan\Videos\HandBrake\ISO_02_F1.mkv
[22:06:12]    + container: Matroska (libavformat)
[22:06:12]      + chapter markers
[22:06:12]  * video track
[22:06:12]    + decoder: mpeg2video
[22:06:12]      + bitrate 200 kbps
[22:06:12]    + filters
[22:06:12]      + Comb Detect (mode=3:spatial-metric=2:motion-thresh=1:spatial-thresh=1:filter-mode=2:block-thresh=40:block-width=16:block-height=16)
[22:06:12]      + Decomb (mode=39)
[22:06:12]      + Framerate Shaper (mode=1)
[22:06:12]        + frame rate: 25.000 fps -> constant 25.000 fps
[22:06:12]      + Crop and Scale (width=720:height=576:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[22:06:12]        + source: 720 * 576, crop (0/0/0/0): 720 * 576, scale: 720 * 576
[22:06:12]    + Output geometry
[22:06:12]      + storage dimensions: 720 x 576
[22:06:12]      + pixel aspect ratio: 64 : 45
[22:06:12]      + display dimensions: 1024 x 576
[22:06:12]    + encoder: H.264 (libx264)
[22:06:12]      + preset:  medium
[22:06:12]      + profile: main
[22:06:12]      + level:   3.1
[22:06:12]      + quality: 19.00 (RF)
[22:06:12]      + color profile: 5-1-6
[22:06:12]  * subtitle track 1, Deutsch (Wide Screen) [VOBSUB] (track 1, id 0x21bd, Picture) -> Passthrough
[22:06:12]  * subtitle track 2, English (Wide Screen) [VOBSUB] (track 0, id 0x20bd, Picture) -> Passthrough
[22:06:12]  * audio track 1
[22:06:12]    + decoder: Deutsch (AC3) (5.1 ch) (448 kbps) (track 2, id 0x81bd)
[22:06:12]      + bitrate: 448 kbps, samplerate: 48000 Hz
[22:06:12]    + AC3 Passthru
[22:06:12]  * audio track 2
[22:06:12]    + decoder: English (AC3) (5.1 ch) (448 kbps) (track 1, id 0x80bd)
[22:06:12]      + bitrate: 448 kbps, samplerate: 48000 Hz
[22:06:12]    + AC3 Passthru
libdvdnav: Using dvdnav version 6.0.0
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: ISO_2
libdvdnav: DVD Serial Number: 4af29bb5
libdvdnav: DVD Title (Alternative):
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[22:06:12] sync: expecting 58500 video frames
[22:06:12] encx264: encoding at constant RF 19.000000
[22:06:12] encx264: unparsed options: level=3.1:8x8dct=0:vbv-bufsize=14000:vbv-maxrate=14000
x264 [info]: using SAR=64/45
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2
x264 [info]: profile Main, level 3.1, 4:2:0, 8-bit
[22:06:12] sync: first pts video is 0
[22:06:12] sync: "Chapter 1" (1) at frame 1 time 0
[22:06:12] sync: first pts audio 0x81bd is 0
[22:06:12] sync: first pts audio 0x80bd is 0
[22:06:13] sync: first pts subtitle 0x20bd is 496800
[22:06:19] sync: first pts subtitle 0x21bd is 1738800
[22:07:45] sync: "Chapter 2" (2) at frame 4834 time 17398800
[22:09:16] sync: "Chapter 3" (3) at frame 10291 time 37044000
[22:12:37] sync: "Chapter 4" (4) at frame 22745 time 81878400
[22:15:17] sync: "Chapter 5" (5) at frame 33206 time 119538000
[22:18:06] sync: "Chapter 6" (6) at frame 44473 time 160099200
[22:21:25] sync: "Chapter 7" (7) at frame 57821 time 208152000
[22:21:29] reader: done. 1 scr changes
[22:21:31] work: average encoding speed for job is 63.685406 fps
[22:21:31] comb detect: heavy 5585 | light 11298 | uncombed 41617 | total 58500
[22:21:31] decomb: deinterlaced 5585 | blended 11298 | unfiltered 41617 | total 58500
[22:21:31] vfr: 58500 frames output, 0 dropped and 0 duped for CFR/PFR
[22:21:31] vfr: lost time: 0 (0 frames)
[22:21:31] vfr: gained time: 0 (0 frames) (0 not accounted for)
[22:21:31] ac3-decoder done: 73124 frames, 0 decoder errors
[22:21:31] ac3-decoder done: 73124 frames, 0 decoder errors
[22:21:31] mpeg2video-decoder done: 58500 frames, 0 decoder errors
[22:21:31] sync: got 58500 frames, 58500 expected
[22:21:31] sync: framerate min 25.000 fps, max 25.000 fps, avg 25.000 fps
x264 [info]: frame I:987   Avg QP:15.47  size: 42041
x264 [info]: frame P:19512 Avg QP:19.06  size: 13749
x264 [info]: frame B:38001 Avg QP:21.84  size:  4168
x264 [info]: consecutive B-frames:  6.9%  9.6% 30.0% 53.6%
x264 [info]: mb I  I16..4: 23.4%  0.0% 76.6%
x264 [info]: mb P  I16..4:  4.3%  0.0%  8.6%  P16..4: 41.4% 21.1% 11.8%  0.0%  0.0%    skip:12.8%
x264 [info]: mb B  I16..4:  0.3%  0.0%  0.8%  B16..8: 45.9%  6.7%  1.5%  direct: 4.0%  skip:40.9%  L0:39.5% L1:47.1% BI:13.5%
x264 [info]: coded y,uvDC,uvAC intra: 69.9% 81.7% 53.2% inter: 16.8% 28.8% 3.6%
x264 [info]: i16 v,h,dc,p: 40% 16%  9% 35%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 31% 16% 14%  6%  7%  8%  6%  7%  5%
x264 [info]: i8c dc,h,v,p: 46% 16% 28%  9%
x264 [info]: Weighted P-Frames: Y:2.2% UV:1.5%
x264 [info]: ref P L0: 63.4% 14.8% 16.0%  5.7%  0.1%
x264 [info]: ref B L0: 91.1%  7.5%  1.4%
x264 [info]: ref B L1: 97.8%  2.2%
x264 [info]: kb/s:1600.52
[22:21:31] mux: track 0, 58500 frames, 468114226 bytes, 1600.36 kbps, fifo 2048
[22:21:31] mux: track 1, 73124 frames, 131038208 bytes, 447.99 kbps, fifo 2048
[22:21:31] mux: track 2, 73124 frames, 131038208 bytes, 447.99 kbps, fifo 2048
[22:21:31] mux: track 3, 688 frames, 2235888 bytes, 7.64 kbps, fifo 32
[22:21:31] mux: track 4, 675 frames, 2091464 bytes, 7.15 kbps, fifo 32
[22:21:31] libhb: work result = 0

I hope, I copied the right one. But I am afraid it is useless because the log is about the mkv-file "ISO_02_F1.mkv" but the problem mkv-file is "ISO_02_F2.mkv" and for that file there is no log in the activity folder. Starting encoding of "ISO_02_F2.mkv" at about 22:22.

A restart an simple retry creates "ISO_02_F2.mkv" at 03:22 this morning!?!?

And, as I said before, there are no problems with the offical 1.2.2 version of handbrake.

PS: the name of the log file is "activity_log2624.txt" not "ISO_01_F6.mkv 06-02-2019 22-06-04.txt" for example if the encoding was successful.
PPS: Maybe it is not a problem at the beginning of encoding of "ISO_02_F2.mkv" but at the end of "ISO_02_1.mkv", just a guess.