HandBrake / HandBrake

HandBrake's main development repository
https://handbrake.fr
Other
17.05k stars 1.31k forks source link

H265 encode using QSV is failing with Iris Xe 750 #3530

Closed chrismrutledge closed 3 years ago

chrismrutledge commented 3 years ago

Problem description:

Current HB release is only giving me about 25fps on h265 encodes so I figured, because the GPU is so new, I'd try the nightly and see if support had been added. So far, all files are failing with either an hb_qsv_wait_on_sync failed -17 or -21

UHD 750 driver is from Intel v27.20.100.9316

HandBrake version (e.g., 1.3.0):

Nightly 20210403145615-e58b78c6f-master (2021040401)

Operating system and version (e.g., Ubuntu 19.10, macOS 10.15 Catalina, Windows 10 1909):

Windows Server 2019 (1809)

Error message text and/or screenshot:

hb_qsv_wait_on_sync failed -17 or -21

HandBrake Activity Log:

HandBrake Nightly 20210403145615-e58b78c6f-master (2021040401)
OS: Microsoft Windows NT 10.0.17763.0
CPU: 11th Gen Intel(R) Core(TM) i7-11700 @ 2.50GHz
Ram: 65318 MB, 
GPU Information:
  Intel(R) UHD Graphics 750 - 27.20.100.9316
Screen: 1824x993
Temp Dir: C:\Users\Administrator\AppData\Local\Temp\1\
Install Dir: C:\Users\Administrator\Downloads\HandBrake-20210404-e58b78c6f-x86_64-Win_GUI\HandBrake\
Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake\Nightly

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

# Starting Encode ...
[08:33:37] base preset: H.265 QSV 1080p (Modified)
Remote Process started with Process ID: 7696 using port: 8037. Max Allowed Instances: 1
Worker: Starting HandBrake Engine ...
Worker: Starting Web Server on port 8037 ...
Worker: Disconnected worker monitoring enabled!
[08:33:38] Compile-time hardening features are enabled
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
[08:33:38] hb_init: starting libhb thread
[08:33:38] Starting work at: Tue Apr 06 08:33:38 2021
[08:33:38] 1 job(s) to process
[08:33:38] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 384,
        "DRC": 0,
        "Encoder": "eac3",
        "Gain": 0,
        "Mixdown": 7,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Name": "Surround",
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      "copy:aac",
      "copy:ac3",
      "copy:dtshd",
      "copy:dts",
      "copy:eac3",
      "copy:flac",
      "copy:mp3",
      "copy:truehd",
      "copy:mp2"
    ],
    "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"
      },
      {
        "Name": "Chapter 8"
      },
      {
        "Name": "Chapter 9"
      },
      {
        "Name": "Chapter 10"
      },
      {
        "Name": "Chapter 11"
      },
      {
        "Name": "Chapter 12"
      },
      {
        "Name": "Chapter 13"
      },
      {
        "Name": "Chapter 14"
      },
      {
        "Name": "Chapter 15"
      },
      {
        "Name": "Chapter 16"
      },
      {
        "Name": "Chapter 17"
      },
      {
        "Name": "Chapter 18"
      },
      {
        "Name": "Chapter 19"
      },
      {
        "Name": "Chapter 20"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": false,
    "File": "R:\\Working\\Firefly S01E01 Serenity.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "av_mp4"
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 12,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "1080",
          "width": "1920"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "0"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 20
    },
    "Title": 1,
    "Path": "S:\\Working\\Firefly\\Firefly S01E01 Serenity.mkv"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": true,
      "Forced": false
    },
    "SubtitleList": [
      {
        "Burn": true,
        "Default": false,
        "Forced": false,
        "ID": 1,
        "Offset": 0,
        "Track": 0
      }
    ]
  },
  "Video": {
    "Encoder": "qsv_h265_10bit",
    "Level": "auto",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "lowpower=1",
    "Preset": "speed",
    "Profile": "auto",
    "Quality": 22,
    "QSV": {
      "Decode": true
    }
  }
}
[08:33:38] CPU: 11th Gen Intel(R) Core(TM) i7-11700 @ 2.50GHz
[08:33:38]  - logical processor count: 16
[08:33:38] Intel Quick Sync Video support: yes, gpu list: 0
[08:33:38] Intel Quick Sync Video integrated adapter with index 0
[08:33:38]  - Intel Media SDK hardware: API 1.34 (minimum: 1.3)
[08:33:38]  - Decode support:  h264 hevc (8bit: yes, 10bit: yes) av1 (8bit: yes, 10bit: yes)
[08:33:38]  - H.264 encoder: yes
[08:33:38]     - preferred implementation: hardware (1) via D3D11
[08:33:38]     - capabilities (hardware):  breftype icq+la+i+downs vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice
[08:33:38]  - H.265 encoder: yes (8bit: yes, 10bit: yes)
[08:33:38]     - preferred implementation: hardware (1) via D3D11
[08:33:38]     - capabilities (hardware):  lowpower bpyramid icq vsinfo opt1
[08:33:38] hb_scan: path=S:\Working\Firefly\Firefly S01E01 Serenity.mkv, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image S:\Working\Firefly\Firefly S01E01 Serenity.mkv
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
src/libbluray/bluray.c:2585: nav_get_title_list(S:\Working\Firefly\Firefly S01E01 Serenity.mkv\) failed
[08:33:38] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 6.0.1
libdvdread: Encrypted DVD support unavailable.
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
[08:33:38] dvd: not a dvd - trying as a stream/file instead
Input #0, matroska,webm, from 'S:\Working\Firefly\Firefly S01E01 Serenity.mkv':
  Metadata:
    title           : FIREFLY: DISC 1
    CREATION_TIME   : 2018-12-26T17:37:32Z
    ENCODER         : Lavf57.7.2
  Duration: 01:26:42.21, start: 0.000000, bitrate: 5777 kb/s
    Chapter #0:0: start 0.000000, end 306.515000
    Metadata:
      title           : Chapter 1
    Chapter #0:1: start 306.515000, end 658.908000
    Metadata:
      title           : Chapter 2
    Chapter #0:2: start 658.908000, end 710.710000
    Metadata:
      title           : Chapter 3
    Chapter #0:3: start 710.710000, end 1006.923000
    Metadata:
      title           : Chapter 4
    Chapter #0:4: start 1006.923000, end 1326.826000
    Metadata:
      title           : Chapter 5
    Chapter #0:5: start 1326.826000, end 1657.990000
    Metadata:
      title           : Chapter 6
    Chapter #0:6: start 1657.990000, end 2060.100000
    Metadata:
      title           : Chapter 7
    Chapter #0:7: start 2060.100000, end 2164.496000
    Metadata:
      title           : Chapter 8
    Chapter #0:8: start 2164.496000, end 2532.613000
    Metadata:
      title           : Chapter 9
    Chapter #0:9: start 2532.613000, end 2863.903000
    Metadata:
      title           : Chapter 10
    Chapter #0:10: start 2863.903000, end 3026.524000
    Metadata:
      title           : Chapter 11
    Chapter #0:11: start 3026.524000, end 3180.427000
    Metadata:
      title           : Chapter 12
    Chapter #0:12: start 3180.427000, end 3410.991000
    Metadata:
      title           : Chapter 13
    Chapter #0:13: start 3410.991000, end 3535.782000
    Metadata:
      title           : Chapter 14
    Chapter #0:14: start 3535.782000, end 3699.988000
    Metadata:
      title           : Chapter 15
    Chapter #0:15: start 3699.988000, end 3866.863000
    Metadata:
      title           : Chapter 16
    Chapter #0:16: start 3866.863000, end 4403.065000
    Metadata:
      title           : Chapter 17
    Chapter #0:17: start 4403.065000, end 4839.793000
    Metadata:
      title           : Chapter 18
    Chapter #0:18: start 4839.793000, end 5148.477000
    Metadata:
      title           : Chapter 19
    Chapter #0:19: start 5148.477000, end 5201.196000
    Metadata:
      title           : Chapter 20
    Stream #0:0: Video: hevc (Main), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 23.98 tbc (default)
    Stream #0:1(eng): Audio: dts (DTS-HD MA), 48000 Hz, 5.1(side), s32p (24 bit) (default)
    Metadata:
      title           : Surround
    Stream #0:2(eng): Subtitle: hdmv_pgs_subtitle, 1920x1080
[08:33:38] scan: decoding previews for title 1
[08:33:38] scan: audio 0x1: dca, rate=48000Hz, bitrate=1 English (DTS-HD MA) (5.1 ch)
[08:33:39] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1, color profile: 1-1-1
[08:33:39] scan: supported video decoders: avcodec qsv
[08:33:39] libhb: scan thread found 1 valid title(s)
[08:33:39] Skipping subtitle scan.  No suitable subtitle tracks.
[08:33:39] Starting Task: Encoding Pass
[08:33:39] Skipping vfr filter
[08:33:39] Skipping crop/scale filter
[08:33:39] work: track 1, dithering not supported by codec
[08:33:39] job configuration:
[08:33:39]  * source
[08:33:39]    + S:\Working\Firefly\Firefly S01E01 Serenity.mkv
[08:33:39]    + title 1, chapter(s) 1 to 20
[08:33:39]    + container: matroska,webm
[08:33:39]    + data rate: 5777 kbps
[08:33:39]  * destination
[08:33:39]    + R:\Working\Firefly S01E01 Serenity.m4v
[08:33:39]    + container: MPEG-4 (libavformat)
[08:33:39]      + chapter markers
[08:33:39]  * video track
[08:33:39]    + decoder: hevc_qsv 8-bit
[08:33:39]    + filter
[08:33:39]      + Subtitle renderer ()
[08:33:39]    + Output geometry
[08:33:39]      + storage dimensions: 1920 x 1080
[08:33:39]      + pixel aspect ratio: 1 : 1
[08:33:39]      + display dimensions: 1920 x 1080
[08:33:39]    + encoder: H.265 10-bit (Intel Media SDK)
[08:33:39]      + preset:  speed
[08:33:39]      + options: lowpower=1
[08:33:39]      + profile: auto
[08:33:39]      + level:   auto
[08:33:39]      + quality: 22.00 (ICQ)
[08:33:39]      + color profile: 1-1-1
[08:33:39]  * subtitle track 1, English [PGS] (track 0, id 0x2, Picture) -> Render/Burn-in
[08:33:39]  * audio track 1
[08:33:39]    + name: Surround
[08:33:39]    + decoder: English (DTS-HD MA) (5.1 ch) (track 1, id 0x1)
[08:33:39]      + samplerate: 48000 Hz
[08:33:39]    + mixdown: 5.1 Channels
[08:33:39]    + encoder: E-AC3 (libavcodec)
[08:33:39]      + bitrate: 384 kbps, samplerate: 48000 Hz
[08:33:39] sync: expecting 124728 video frames
[08:33:39] qsv: integrated qsv adapter with index 0 has been selected
[08:33:39] encqsvInit: using encode-only (LowPower) path
[08:33:39] encqsvInit: H.265/HEVC Main 10 profile @ level (null)
[08:33:39] encqsvInit: TargetUsage 7 AsyncDepth 6
[08:33:39] encqsvInit: GopRefDist 1 GopPicSize 24 NumRefFrame 1
[08:33:39] encqsvInit: BFramesMax 0 BRefType off
[08:33:39] encqsvInit: RateControlMethod ICQ ICQQuality 22
[08:33:39] sync: first pts video is 0
[08:33:39] sync: "Chapter 1" (1) at frame 1 time 0
[08:33:39] sync: first pts audio 0x1 is 0
[08:33:39] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34
Error code -17, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-17)
encqsvWork: qsv_enc_work failed -17
[08:33:41] work: average encoding speed for job is 0.000000 fps
[08:33:42] dca-decoder done: 325 frames, 0 decoder errors
[08:33:42] hevc_qsv-decoder done: 64 frames, 0 decoder errors
[08:33:42] sync: got 35 frames, 124728 expected
[08:33:42] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[08:33:42] mux: track 0, 0 frames, 0 bytes, 0.00 kbps, fifo 8
[08:33:42] mux: track 1, 44 frames, 67584 bytes, 381.27 kbps, fifo 64
[08:33:42] Finished work at: Tue Apr 06 08:33:42 2021
[08:33:42] libhb: work result = 4

# Job Failed (4)
woodstockathbf commented 3 years ago

Does it still fail if you disable hardware DECODE?

The decoder on QSV is rather intolerant of some video streams.

chrismrutledge commented 3 years ago

Yes.. Same failure, same error with decoding off...

image

sr55 commented 3 years ago

@maximd33 do we know what the driver situation is for these new parts?

@chrismrutledge on the off-chance, can you do a short encode without subtitles to verify it's related to that?

chrismrutledge commented 3 years ago

@sr55 Somewhat same issue.. Got -21 for the error code this time instead of -17..

HandBrake Nightly 20210403145615-e58b78c6f-master (2021040401) OS: Microsoft Windows NT 10.0.17763.0 CPU: 11th Gen Intel(R) Core(TM) i7-11700 @ 2.50GHz Ram: 65318 MB, GPU Information: Intel(R) UHD Graphics 750 - 27.20.100.9316 Screen: 1824x993 Temp Dir: C:\Users\Administrator\AppData\Local\Temp\1\ Install Dir: C:\Users\Administrator\Downloads\HandBrake-20210404-e58b78c6f-x86_64-Win_GUI\HandBrake\ Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake\Nightly


Starting Encode ...

[12:30:43] base preset: H.265 QSV 2160p 4K (Modified) Remote Process started with Process ID: 2460 using port: 8037. Max Allowed Instances: 1 Worker: Starting HandBrake Engine ... Worker: Starting Web Server on port 8037 ... Worker: Disconnected worker monitoring enabled! [12:30:43] Compile-time hardening features are enabled Cannot load nvEncodeAPI64.dll Cannot load nvEncodeAPI64.dll Cannot load nvEncodeAPI64.dll Cannot load nvEncodeAPI64.dll [12:30:44] hb_init: starting libhb thread [12:30:44] Starting work at: Tue Apr 06 12:30:44 2021 [12:30:44] 1 job(s) to process [12:30:44] json job: { "Audio": { "AudioList": [ { "Bitrate": 160, "DRC": 0, "Encoder": "av_aac", "Gain": 0, "Mixdown": 4, "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", "copy:mp2" ], "FallbackEncoder": "ac3" }, "Destination": { "ChapterList": [ { "Name": "Chapter 1" } ], "ChapterMarkers": true, "AlignAVStart": false, "File": "R:\Working\Big Buck Bunny 4K-1.mkv", "Mp4Options": { "IpodAtom": false, "Mp4Optimize": false }, "Mux": "av_mkv" }, "Filters": { "FilterList": [ { "ID": 12, "Settings": { "crop-bottom": "0", "crop-left": "0", "crop-right": "0", "crop-top": "0", "height": "2250", "width": "4000" } }, { "ID": 6, "Settings": { "mode": "0" } } ] }, "PAR": { "Num": 1, "Den": 1 }, "SequenceID": 0, "Source": { "Angle": 1, "Range": { "Type": "chapter", "Start": 1, "End": 1 }, "Title": 1, "Path": "S:\Working\Big_Buck_Bunny_4K.webm" }, "Subtitle": { "Search": { "Burn": false, "Default": false, "Enable": false, "Forced": false }, "SubtitleList": [] }, "Video": { "Encoder": "qsv_h265_10bit", "Level": "auto", "TwoPass": false, "Turbo": false, "ColorMatrixCode": 0, "Options": "lowpower=1", "Preset": "speed", "Profile": "auto", "Quality": 24, "QSV": { "Decode": false } } } [12:30:44] CPU: 11th Gen Intel(R) Core(TM) i7-11700 @ 2.50GHz [12:30:44] - logical processor count: 16 [12:30:44] Intel Quick Sync Video support: yes, gpu list: 0 [12:30:44] Intel Quick Sync Video integrated adapter with index 0 [12:30:44] - Intel Media SDK hardware: API 1.34 (minimum: 1.3) [12:30:44] - Decode support: h264 hevc (8bit: yes, 10bit: yes) av1 (8bit: yes, 10bit: yes) [12:30:44] - H.264 encoder: yes [12:30:44] - preferred implementation: hardware (1) via D3D11 [12:30:44] - capabilities (hardware): breftype icq+la+i+downs vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice [12:30:44] - H.265 encoder: yes (8bit: yes, 10bit: yes) [12:30:44] - preferred implementation: hardware (1) via D3D11 [12:30:44] - capabilities (hardware): lowpower bpyramid icq vsinfo opt1 [12:30:44] hb_scan: path=S:\Working\Big_Buck_Bunny_4K.webm, title_index=1 udfread ERROR: ECMA 167 Volume Recognition failed src/libbluray/disc/disc.c:323: failed opening UDF image S:\Working\Big_Buck_Bunny_4K.webm 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 src/libbluray/bluray.c:2585: nav_get_title_list(S:\Working\Big_Buck_Bunny_4K.webm) failed [12:30:44] bd: not a bd - trying as a stream/file instead libdvdnav: Using dvdnav version 6.0.1 libdvdread: Encrypted DVD support unavailable. 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 [12:30:44] dvd: not a dvd - trying as a stream/file instead Input #0, matroska,webm, from 'S:\Working\Big_Buck_Bunny_4K.webm': Metadata: title : Big Buck Bunny, Sunflower version encoder : Lavf54.20.4 Duration: 00:10:34.55, start: 0.000000, bitrate: 37378 kb/s Stream #0:0: Video: vp8, yuv420p(progressive), 4000x2250, SAR 1:1 DAR 16:9, 30k fps, 60 tbr, 1k tbn, 1k tbc (default) Stream #0:1: Audio: vorbis, 48000 Hz, 5.1, fltp (default) [12:30:44] scan: decoding previews for title 1 [12:30:44] scan: audio 0x1: vorbis, rate=48000Hz, bitrate=1 Unknown (Vorbis) (5.1 ch) [12:30:45] scan: 10 previews, 4000x2250, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1, color profile: 1-1-5 [12:30:45] libhb: scan thread found 1 valid title(s) [12:30:45] Starting Task: Encoding Pass [12:30:45] Skipping vfr filter [12:30:45] Skipping crop/scale filter [12:30:45] work: track 1, dithering not supported by codec [12:30:45] work: only 1 chapter, disabling chapter markers [12:30:45] job configuration: [12:30:45] source [12:30:45] + S:\Working\Big_Buck_Bunny_4K.webm [12:30:45] + title 1, chapter(s) 1 to 1 [12:30:45] + container: matroska,webm [12:30:45] + data rate: 37378 kbps [12:30:45] destination [12:30:45] + R:\Working\Big Buck Bunny 4K-1.mkv [12:30:45] + container: Matroska (libavformat) [12:30:45] video track [12:30:45] + decoder: vp8 8-bit [12:30:45] + Output geometry [12:30:45] + storage dimensions: 4000 x 2250 [12:30:45] + pixel aspect ratio: 1 : 1 [12:30:45] + display dimensions: 4000 x 2250 [12:30:45] + encoder: H.265 10-bit (Intel Media SDK) [12:30:45] + preset: speed [12:30:45] + options: lowpower=1 [12:30:45] + profile: auto [12:30:45] + level: auto [12:30:45] + quality: 24.00 (ICQ) [12:30:45] + color profile: 1-1-5 [12:30:45] audio track 1 [12:30:45] + decoder: Unknown (Vorbis) (5.1 ch) (track 1, id 0x1) [12:30:45] + samplerate: 48000 Hz [12:30:45] + mixdown: Stereo [12:30:45] + encoder: AAC (libavcodec) [12:30:45] + bitrate: 160 kbps, samplerate: 48000 Hz [12:30:45] sync: expecting 15214 video frames [12:30:45] qsv: integrated qsv adapter with index 0 has been selected [12:30:45] encqsvInit: using encode-only (LowPower) path [12:30:45] encqsvInit: H.265/HEVC Main 10 profile @ level (null) [12:30:45] encqsvInit: TargetUsage 7 AsyncDepth 6 [12:30:45] encqsvInit: GopRefDist 1 GopPicSize 24 NumRefFrame 1 [12:30:45] encqsvInit: BFramesMax 0 BRefType off [12:30:45] encqsvInit: RateControlMethod ICQ ICQQuality 24 [12:30:45] sync: first pts audio 0x1 is 0 [12:30:45] sync: first pts video is 2970 [12:30:45] sync: "Chapter 1" (1) at frame 1 time 2970 [12:30:45] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34 Error code -21, hb_qsv_wait_on_sync 607 encqsv: hb_qsv_wait_on_sync failed (-21) encqsvWork: qsv_enc_work failed -21 [12:30:48] work: average encoding speed for job is 0.000000 fps [12:30:48] vorbis-decoder done: 75 frames, 0 decoder errors [12:30:48] vp8-decoder done: 79 frames, 0 decoder errors [12:30:48] sync: got 46 frames, 15214 expected [12:30:48] sync: framerate min 58.824 fps, max 62.500 fps, avg 59.974 fps [12:30:49] mux: track 0, 3 frames, 29292 bytes, 295.71 kbps, fifo 8 [12:30:49] mux: track 1, 36 frames, 14933 bytes, 150.75 kbps, fifo 64 [12:30:49] Finished work at: Tue Apr 06 12:30:49 2021 [12:30:49] libhb: work result = 4

Job Failed (4)

maximd33 commented 3 years ago

any particular reason for using Windows Server 2019 (1809) ? as driver is Windows OS client focused.

second point needs to be clarified: 8bit decode and 10bit encode?

can you try 8bit encode?

chrismrutledge commented 3 years ago

@maximd33

Wanted to use Server OS for Server.. Containers, User Management, SMB Shares, etc..

Get the same result with QSV and QSV 10-Bit

galinart commented 3 years ago

@chrismrutledge thanks for the reporting. I have reproduced that, need further investigation.

chrismrutledge commented 3 years ago

Anyone know if this might possibly be an Intel Driver problem? As I noted in my original report, I'm getting abysmally slow performance out of a chip that should be able to process h.265 video at 50x the speed I am seeing. HandBrake, FFMPEG, anything I try just moves slow.

sr55 commented 3 years ago

@chrismrutledge slow performance is not related to this issue so please create a ticket with an appropriate log.

chrismrutledge commented 3 years ago

Well, fair enough. But I believe the slow performance issue is an Intel Driver problem since I see the problem with more than just Handbrake.. So, should I post the issue here or try to figure out how to get Intel on the hook for it?

sr55 commented 3 years ago

Please post a separate issue with the log and info requested in the post template, and if we can see that it doesn't look like a HandBrake issue we have friends at Intel we can flag it with to look into.

galinart commented 3 years ago

Anyone know if this might possibly be an Intel Driver problem? As I noted in my original report, I'm getting abysmally slow performance out of a chip that should be able to process h.265 video at 50x the speed I am seeing. HandBrake, FFMPEG, anything I try just moves slow.

Thanks for your feedback! Please submit a separate issue as @sr55 recommended.

In regards to "Error code -21" above, it is under investigation. I will update you as soon as I get more information.

chrismrutledge commented 3 years ago

Roger that! New issue already created at

https://github.com/HandBrake/HandBrake/issues/3547

Appreciate the help!

tvillini commented 3 years ago

Forgive me, I'm new here. I'm getting the same basic error using HB 1.3.3 on an Iris 655 running driver version 27.20.100.8681. I haven't had this problem before, and I think I just upgraded to 1.3.3. In my case, it's not just speed. It's definitely slower... but it also just stops encoding all together (depending on the length of the movie around 4-7% in) and eventually gives up and fails. `# Starting Encode ...

[12:51:43] base preset: H265 Encode [12:51:43] hb_init: starting libhb thread [12:51:43] Starting work at: Fri May 07 12:51:43 2021 [12:51:43] 1 job(s) to process [12:51:43] json job: { "Audio": { "AudioList": [ { "DRC": 0.0, "Encoder": "copy:ac3", "Gain": 0.0, "Mixdown": -1, "NormalizeMixLevel": false, "Samplerate": 48000, "Track": 0, "DitherMethod": 0 }, { "DRC": 0.0, "Encoder": "copy:truehd", "Gain": 0.0, "Mixdown": -1, "NormalizeMixLevel": false, "Samplerate": 48000, "Track": 1, "DitherMethod": 0 }, { "DRC": 0.0, "Encoder": "copy:ac3", "Gain": 0.0, "Mixdown": -1, "NormalizeMixLevel": false, "Samplerate": 48000, "Track": 5, "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" }, { "Name": "Chapter 8" }, { "Name": "Chapter 9" }, { "Name": "Chapter 10" }, { "Name": "Chapter 11" }, { "Name": "Chapter 12" }, { "Name": "Chapter 13" }, { "Name": "Chapter 14" }, { "Name": "Chapter 15" }, { "Name": "Chapter 16" }, { "Name": "Chapter 17" }, { "Name": "Chapter 18" }, { "Name": "Chapter 19" }, { "Name": "Chapter 20" } ], "ChapterMarkers": true, "AlignAVStart": false, "File": "E:\OneDrive\Movies\Madagascar.mkv", "Mp4Options": { "IpodAtom": false, "Mp4Optimize": false }, "Mux": "av_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": "1080", "width": "1920" } }, { "ID": 6, "Settings": { "mode": "1" } } ] }, "PAR": { "Num": 1, "Den": 1 }, "Metadata": {}, "SequenceID": 0, "Source": { "Angle": 1, "Range": { "Type": "chapter", "Start": 1, "End": 20 }, "Title": 1, "Path": "C:\Users\tvill\Desktop\Burn\backup\MADAGASCAR" }, "Subtitle": { "Search": { "Burn": false, "Default": false, "Enable": false, "Forced": false }, "SubtitleList": [] }, "Video": { "Encoder": "qsv_h265_10bit", "Level": "auto", "TwoPass": false, "Turbo": false, "ColorMatrixCode": 0, "Options": "", "Preset": "balanced", "Profile": "auto", "Quality": 20.0, "QSV": { "Decode": true, "AsyncDepth": 0 } } } [12:51:43] CPU: Intel(R) Core(TM) i7-8559U CPU @ 2.70GHz [12:51:43] - Intel microarchitecture Kaby Lake [12:51:43] - logical processor count: 8 [12:51:43] Intel Quick Sync Video support: yes [12:51:43] - Intel Media SDK hardware: API 1.33 (minimum: 1.3) [12:51:43] - H.264 encoder: yes [12:51:43] - preferred implementation: hardware (any) via D3D11 [12:51:43] - capabilities (hardware): breftype icq+la+i+downs vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice [12:51:43] - H.265 encoder: yes (8bit: yes, 10bit: yes) [12:51:43] - preferred implementation: hardware (any) via D3D11 [12:51:43] - capabilities (hardware): bpyramid icq vsinfo opt1 [12:51:43] hb_scan: path=C:\Users\tvill\Desktop\Burn\backup\MADAGASCAR, title_index=1 src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\ src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\ src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library [12:51:43] scan: BD has 65 title(s) [12:51:43] bd: scanning title 1 [12:51:43] bd: playlist 00000.MPLS [12:51:43] bd: duration is 01:25:54 (5154774 ms) [12:51:43] bd: video id=0x1011, stream type=H.264, format 1080p [12:51:43] bd: aspect = 16:9 [12:51:43] bd: audio id=0x761100, lang=English (AC3), 3cc=eng [12:51:43] bd: audio id=0x721100, lang=English (TrueHD), 3cc=eng [12:51:43] bd: audio id=0x1101, lang=Francais (AC3), 3cc=fra [12:51:43] bd: audio id=0x1102, lang=español (AC3), 3cc=spa [12:51:43] bd: audio id=0x1103, lang=Portugues (AC3), 3cc=por [12:51:43] bd: audio id=0x1104, lang=English (AC3), 3cc=eng [12:51:43] bd: subtitle id=0x1200, lang=English [PGS], 3cc=eng [12:51:43] bd: subtitle id=0x1201, lang=English [PGS], 3cc=eng [12:51:43] bd: subtitle id=0x1202, lang=Francais [PGS], 3cc=fra [12:51:43] bd: subtitle id=0x1203, lang=español [PGS], 3cc=spa [12:51:43] bd: subtitle id=0x1204, lang=Portugues [PGS], 3cc=por [12:51:43] bd: subtitle id=0x1209, lang=English [PGS], 3cc=eng [12:51:43] bd: subtitle id=0x1205, lang=English [PGS], 3cc=eng [12:51:43] bd: subtitle id=0x1206, lang=Francais [PGS], 3cc=fra [12:51:43] bd: subtitle id=0x1207, lang=español [PGS], 3cc=spa [12:51:43] bd: subtitle id=0x1208, lang=Portugues [PGS], 3cc=por [12:51:43] bd: chap 1, 333124 ms [12:51:43] bd: chap 2, 180972 ms [12:51:43] bd: chap 3, 245328 ms [12:51:43] bd: chap 4, 316107 ms [12:51:43] bd: chap 5, 275984 ms [12:51:43] bd: chap 6, 263721 ms [12:51:43] bd: chap 7, 242075 ms [12:51:43] bd: chap 8, 272146 ms [12:51:43] bd: chap 9, 275733 ms [12:51:43] bd: chap 10, 268184 ms [12:51:43] bd: chap 11, 257590 ms [12:51:43] bd: chap 12, 206497 ms [12:51:43] bd: chap 13, 298548 ms [12:51:43] bd: chap 14, 299299 ms [12:51:43] bd: chap 15, 279821 ms [12:51:43] bd: chap 16, 340590 ms [12:51:43] bd: chap 17, 222972 ms [12:51:43] bd: chap 18, 118576 ms [12:51:43] bd: chap 19, 37120 ms [12:51:43] bd: chap 20, 420378 ms [12:51:43] bd: title 1 has 20 chapters [12:51:43] scan: decoding previews for title 1 [12:51:43] scan: title angle(s) 1 [12:51:43] scan: audio 0x721100: truehd, rate=48000Hz, bitrate=128000 English (TrueHD) (5.1 ch) (128 kbps) [12:51:43] scan: audio 0x1104: ac3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch) (Dolby Surround) (192 kbps) [12:51:43] scan: audio 0x761100: ac3, rate=48000Hz, bitrate=640000 English (AC3) (5.1 ch) (640 kbps) [12:51:43] scan: audio 0x1101: ac3, rate=48000Hz, bitrate=640000 Francais (AC3) (5.1 ch) (640 kbps) [12:51:43] scan: audio 0x1102: ac3, rate=48000Hz, bitrate=640000 español (AC3) (5.1 ch) (640 kbps) [12:51:43] scan: audio 0x1103: ac3, rate=48000Hz, bitrate=640000 Portugues (AC3) (5.1 ch) (640 kbps) [12:51:45] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1 [12:51:45] scan: supported video decoders: avcodec qsv [12:51:45] stream: 10 good frames, 0 errors (0%) [12:51:45] libhb: scan thread found 1 valid title(s) [12:51:45] Starting Task: Encoding Pass [12:51:45] Skipping crop/scale filter [12:51:45] job configuration: [12:51:45] source [12:51:45] + C:\Users\tvill\Desktop\Burn\backup\MADAGASCAR [12:51:45] + title 1, chapter(s) 1 to 20 [12:51:45] destination [12:51:45] + E:\OneDrive\Movies\Madagascar.mkv [12:51:45] + container: Matroska (libavformat) [12:51:45] + chapter markers [12:51:45] video track [12:51:45] + decoder: h264_qsv [12:51:45] + bitrate 200 kbps [12:51:45] + filters [12:51:45] + 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) [12:51:45] + Decomb (mode=39) [12:51:45] + Framerate Shaper (mode=1) [12:51:45] + frame rate: 23.976 fps -> constant 23.976 fps [12:51:45] + Output geometry [12:51:45] + storage dimensions: 1920 x 1080 [12:51:45] + pixel aspect ratio: 1 : 1 [12:51:45] + display dimensions: 1920 x 1080 [12:51:45] + encoder: H.265 10-bit (Intel Media SDK) [12:51:45] + preset: balanced [12:51:45] + profile: auto [12:51:45] + level: auto [12:51:45] + quality: 20.00 (ICQ) [12:51:45] + color profile: 1-1-1 [12:51:45] audio track 1 [12:51:45] + decoder: English (AC3) (5.1 ch) (640 kbps) (track 1, id 0x761100) [12:51:45] + bitrate: 640 kbps, samplerate: 48000 Hz [12:51:45] + AC3 Passthru [12:51:45] audio track 2 [12:51:45] + decoder: English (TrueHD) (5.1 ch) (128 kbps) (track 2, id 0x721100) [12:51:45] + bitrate: 128 kbps, samplerate: 48000 Hz [12:51:45] + TrueHD Passthru [12:51:45] audio track 3 [12:51:45] + decoder: English (AC3) (2.0 ch) (Dolby Surround) (192 kbps) (track 6, id 0x1104) [12:51:45] + bitrate: 192 kbps, samplerate: 48000 Hz [12:51:45] + AC3 Passthru src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\ src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\ src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library [12:51:45] hb_d3d11va_device_check: QSV adapter with id 0 has been found [12:51:45] sync: expecting 123590 video frames [12:51:45] encqsvInit: using encode-only path [12:51:45] encqsvInit: H.265/HEVC Main 10 profile @ level (null) [12:51:45] encqsvInit: TargetUsage 4 AsyncDepth 5 [12:51:45] encqsvInit: GopRefDist 3 GopPicSize 24 NumRefFrame 2 [12:51:45] encqsvInit: BFramesMax 2 BRefType off [12:51:45] encqsvInit: RateControlMethod ICQ ICQQuality 20 [12:51:45] sync: first pts audio 0x721100 is 0 [12:51:45] sync: first pts audio 0x1104 is 0 [12:51:45] sync: first pts video is 0 [12:51:45] sync: "Chapter 1" (1) at frame 1 time 0 [12:51:45] sync: first pts audio 0x761100 is 0 [12:51:46] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.33 Error code -21, hb_qsv_wait_on_sync 605`

Any thoughts on what I might be doing wrong? Thanks.

galinart commented 3 years ago

@tvillini I think your setup is fine. Latest available driver is 27.20.100.9466. Have you tried it? Does Nightly build fail with updated driver?

tvillini commented 3 years ago

Just tried the nightly build... and it's kinda like Max Power on the Simpsons. There's the right way, the wrong way, and the Max Power way. It's the wrong way, but faster. :D It quits, but it quits suddenly, as opposed to slowing to a crawl, sitting there doing nothing, and then eventually failing.

[17:51:01] sync: "Chapter 2" (2) at frame 7979 time 29947417
Error code -21, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[17:52:33] work: average encoding speed for job is 46.883057 fps
[17:52:33] comb detect: heavy 29 | light 294 | uncombed 11438 | total 11761
[17:52:33] decomb: deinterlaced 29 | blended 294 | unfiltered 11433 | total 11756
[17:52:33] vfr: 11752 frames output, 0 dropped and 0 duped for CFR/PFR
[17:52:33] vfr: lost time: 0 (0 frames)
[17:52:33] vfr: gained time: 0 (0 frames) (0 not accounted for)
[17:52:33] stream: 11837 good frames, 0 errors (0%)
[17:52:33] ac3-decoder done: 15403 frames, 0 decoder errors
[17:52:33] truehd-decoder done: 591514 frames, 0 decoder errors
[17:52:33] ac3-decoder done: 15420 frames, 0 decoder errors
[17:52:33] h264_qsv-decoder done: 11816 frames, 0 decoder errors
[17:52:33] sync: got 11779 frames, 123590 expected
[17:52:33] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[17:52:33] mux: track 0, 11735 frames, 422478220 bytes, 6858.06 kbps, fifo 256
[17:52:33] mux: track 1, 15373 frames, 39354880 bytes, 638.84 kbps, fifo 512
[17:52:33] mux: track 2, 591314 frames, 214211198 bytes, 3477.27 kbps, fifo 16384
[17:52:33] mux: track 3, 15389 frames, 11818752 bytes, 191.85 kbps, fifo 512
[17:52:33] Finished work at: Fri May 07 17:52:33 2021
[17:52:33] libhb: work result = 4

I'm gonna update to the new driver now. For whatever reason, the Intel Driver application doesn't have it updating for whatever reason.

tvillini commented 3 years ago

OK... now using new driver on release 1.3.3. It lasted longer, but it died out at about 27% complete.

[18:13:12] hb_d3d11va_device_check: QSV adapter with id 0 has been found [18:13:12] sync: expecting 123590 video frames [18:13:12] encqsvInit: using encode-only path [18:13:12] encqsvInit: H.265/HEVC Main 10 profile @ level (null) [18:13:12] encqsvInit: TargetUsage 4 AsyncDepth 5 [18:13:12] encqsvInit: GopRefDist 3 GopPicSize 24 NumRefFrame 2 [18:13:12] encqsvInit: BFramesMax 2 BRefType off [18:13:12] encqsvInit: RateControlMethod ICQ ICQQuality 20 [18:13:12] sync: first pts audio 0x721100 is 0 [18:13:12] sync: first pts audio 0x1104 is 0 [18:13:12] sync: first pts video is 0 [18:13:12] sync: "Chapter 1" (1) at frame 1 time 0 [18:13:12] sync: first pts audio 0x761100 is 0 [18:13:13] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34

[18:16:22] sync: "Chapter 2" (2) at frame 7979 time 29947417 [18:18:07] sync: "Chapter 3" (3) at frame 12326 time 46264968 [18:20:25] sync: "Chapter 4" (4) at frame 18191 time 68280712 [18:23:00] sync: "Chapter 5" (5) at frame 25786 time 96790443 [18:25:20] sync: "Chapter 6" (6) at frame 32399 time 121613992 Error code -21, hb_qsv_wait_on_sync 605

Gonna try the nightly build version now.

woodstockathbf commented 3 years ago

[17:52:33] h264_qsv-decoder done: 11816 frames, 0 decoder errors

I have to ask the same question I posed to the original poster - does it work if you disable QSV hardware decoding? The symptoms match when the hardware decoder doesn't like the input stream.

tvillini commented 3 years ago

Same issue with QSV Decoders turned off. Just checked it.

19:09:10] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34
[19:11:27] sync: "Chapter 2" (2) at frame 7979 time 29947417
[19:12:59] sync: "Chapter 3" (3) at frame 12326 time 46264968
[19:14:55] sync: "Chapter 4" (4) at frame 18191 time 68280712
[19:17:29] sync: "Chapter 5" (5) at frame 25786 time 96790443
Error code -21, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[19:19:39] work: average encoding speed for job is 50.847622 fps
[19:19:39] comb detect: heavy 110 | light 658 | uncombed 31093 | total 31861
[19:19:39] decomb: deinterlaced 110 | blended 658 | unfiltered 31085 | total 31853
[19:19:39] vfr: 31845 frames output, 0 dropped and 0 duped for CFR/PFR
[19:19:39] vfr: lost time: 0 (0 frames)
[19:19:39] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:19:40] stream: 31934 good frames, 0 errors (0%)
[19:19:40] ac3-decoder done: 41598 frames, 0 decoder errors
[19:19:40] truehd-decoder done: 1597402 frames, 0 decoder errors
[19:19:40] ac3-decoder done: 41614 frames, 0 decoder errors
[19:19:40] h264_qsv-decoder done: 31913 frames, 0 decoder errors
[19:19:40] sync: got 31879 frames, 123590 expected
[19:19:40] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[19:19:41] mux: track 0, 31831 frames, 833514419 bytes, 5009.55 kbps, fifo 512
[19:19:41] mux: track 1, 41568 frames, 106414080 bytes, 639.57 kbps, fifo 512
[19:19:41] mux: track 2, 1597202 frames, 561096116 bytes, 3372.28 kbps, fifo 32768
[19:19:41] mux: track 3, 41584 frames, 31936512 bytes, 191.94 kbps, fifo 1024

[

Worker process exited!
Worker process exit was not expected.

# Job Failed (-12)
tvillini commented 3 years ago

Tried using VNC instead of Chrome RDP, just in case there was a conflict. Basically did the exact same thing. I'm even watching the task manager performance on the GPU, and it's a sharp and immediate stop of usage on the GPU when it dies.

[19:33:56] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34
[19:36:13] sync: "Chapter 2" (2) at frame 7979 time 29947417
[19:37:46] sync: "Chapter 3" (3) at frame 12326 time 46264968
Error code -21, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[19:39:44] work: average encoding speed for job is 51.702801 fps
[19:39:44] comb detect: heavy 29 | light 304 | uncombed 17579 | total 17912
[19:39:44] decomb: deinterlaced 29 | blended 304 | unfiltered 17574 | total 17907
[19:39:44] vfr: 17903 frames output, 0 dropped and 0 duped for CFR/PFR
[19:39:44] vfr: lost time: 0 (0 frames)
[19:39:44] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:39:44] stream: 17985 good frames, 0 errors (0%)
[19:39:44] ac3-decoder done: 23416 frames, 0 decoder errors
[19:39:44] truehd-decoder done: 899213 frames, 0 decoder errors
[19:39:44] ac3-decoder done: 23432 frames, 0 decoder errors
[19:39:44] h264_qsv-decoder done: 17963 frames, 0 decoder errors
[19:39:45] sync: got 17940 frames, 123590 expected
[19:39:45] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[19:39:45] mux: track 0, 17890 frames, 575190022 bytes, 6141.51 kbps, fifo 512
[19:39:45] mux: track 1, 23386 frames, 59868160 bytes, 639.23 kbps, fifo 512
[19:39:45] mux: track 2, 899014 frames, 318388320 bytes, 3399.55 kbps, fifo 32768
[19:39:45] mux: track 3, 23402 frames, 17972736 bytes, 191.90 kbps, fifo 512
[19

Worker process exited!
Worker process exit was not expected.

# Job Failed (-12)
tvillini commented 3 years ago

Just noticed that it still says h264_qsv decoder on the last two. image

But I had it turned off... I think based on the image above. I restarted and I'm trying it again.

tvillini commented 3 years ago

And it quit again. And it's still saying it's using qsv decode, I think.

[19:46:52] sync: "Chapter 2" (2) at frame 7979 time 29947417
Error code -21, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[19:47:17] work: average encoding speed for job is 49.568695 fps
[19:47:17] comb detect: heavy 29 | light 289 | uncombed 8806 | total 9124
[19:47:17] decomb: deinterlaced 29 | blended 289 | unfiltered 8801 | total 9119
[19:47:17] vfr: 9114 frames output, 0 dropped and 0 duped for CFR/PFR
[19:47:17] vfr: lost time: 0 (0 frames)
[19:47:17] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:47:17] stream: 9190 good frames, 0 errors (0%)
[19:47:17] ac3-decoder done: 11955 frames, 0 decoder errors
[19:47:17] truehd-decoder done: 459111 frames, 0 decoder errors
[19:47:17] ac3-decoder done: 11971 frames, 0 decoder errors
[19:47:17] h264_qsv-decoder done: 9172 frames, 0 decoder errors
[19:47:17] sync: got 9133 frames, 123590 expected
[19:47:17] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[19:47:18] mux: track 0, 9096 frames, 332011744 bytes, 6943.91 kbps, fifo 256
[19:47:18] mux: track 1, 11925 frames, 30528000 bytes, 638.48 kbps, fifo 512
[19:47:18] mux: track 2, 458912 frames, 168282010 bytes, 3519.56 kbps, fifo 16384
[19:47:18] mux: track 3, 11941 frames, 9170688 bytes, 191.80 kbps, fifo 512
[19:47:18] Finished work at: Fri May 07 19:47:18 2021
[19:47:18] libhb: work result = 4

# Job Failed (4)
tvillini commented 3 years ago

Last one... tried plugging in a monitor to see if headless was the problem. Didn't seem to change anything:

encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[19:56:12] work: average encoding speed for job is 48.546528 fps
[19:56:12] comb detect: heavy 29 | light 294 | uncombed 11619 | total 11942
[19:56:12] decomb: deinterlaced 29 | blended 294 | unfiltered 11614 | total 11937
[19:56:12] vfr: 11933 frames output, 0 dropped and 0 duped for CFR/PFR
[19:56:12] vfr: lost time: 0 (0 frames)
[19:56:12] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:56:12] stream: 11996 good frames, 0 errors (0%)
[19:56:12] ac3-decoder done: 15612 frames, 0 decoder errors
[19:56:12] truehd-decoder done: 599507 frames, 0 decoder errors
[19:56:12] ac3-decoder done: 15628 frames, 0 decoder errors
[19:56:12] h264_qsv-decoder done: 11979 frames, 0 decoder errors
[19:56:12] sync: got 11955 frames, 123590 expected
[19:56:12] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[19:56:12] mux: track 0, 11906 frames, 429992915 bytes, 6886.79 kbps, fifo 256
[19:56:12] mux: track 1, 15582 frames, 39889920 bytes, 638.88 kbps, fifo 512
[19:56:12] mux: track 2, 599308 frames, 217595992 bytes, 3485.03 kbps, fifo 16384
[19:56:12] mux: track 3, 15598 frames, 11979264 bytes, 191.86 kbps, fifo 512

Worker process exited!
Worker process exit was not expected.

# Job Failed (-12)
galinart commented 3 years ago

@tvillini Can you please add full logs of the Nightly build and updated the latest driver? I presume logs above using 1.3.3. The complete log contains a lot of details that help to understand the issue.

tvillini commented 3 years ago

logs.zip

@galinart I grabbed everything from today. I believe I updated the driver between the 17-48-14 encode and the 18-47-31 encode. Thanks for the guidance!

maximd33 commented 3 years ago

@tvillini any reasons to use HEVC 10b encode for 8b content? can you try HEVC as 8b?

tvillini commented 3 years ago

That's a really good catch... the source is 8-bit. I'm just making bigger files. But it failed the same way.

# Starting Encode ...
[08:39:45] base preset: H265 Encode
Remote Process started with Process ID: 11596 using port: 8037. Max Allowed Instances: 1
Worker: Starting HandBrake Engine ...
Worker: Starting Web Server on port 8037 ...
Worker: Disconnected worker monitoring enabled!
[08:39:45] Compile-time hardening features are enabled
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
Cannot load nvEncodeAPI64.dll
08:39:46] hb_init: starting libhb thread
[08:39:46] Starting work at: Sat May 08 08:39:46 2021
[08:39:46] 1 job(s) to process
[08:39:46] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0,
        "Encoder": "copy:ac3",
        "Gain": 0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 0,
        "DitherMethod": 0
      },
      {
        "DRC": 0,
        "Encoder": "copy:truehd",
        "Gain": 0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 1,
        "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"
      },
      {
        "Name": "Chapter 8"
      },
      {
        "Name": "Chapter 9"
      },
      {
        "Name": "Chapter 10"
      },
      {
        "Name": "Chapter 11"
      },
      {
        "Name": "Chapter 12"
      },
      {
        "Name": "Chapter 13"
      },
      {
        "Name": "Chapter 14"
      },
      {
        "Name": "Chapter 15"
      },
      {
        "Name": "Chapter 16"
      },
      {
        "Name": "Chapter 17"
      },
      {
        "Name": "Chapter 18"
      },
      {
        "Name": "Chapter 19"
      },
      {
        "Name": "Chapter 20"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": false,
    "File": "E:\\OneDrive\\Movies\\Madagascar.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "av_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": 13,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "1080",
          "width": "1920"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "1"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 20
    },
    "Title": 1,
    "Path": "C:\\Users\\tvill\\Desktop\\Burn\\backup\\MADAGASCAR"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": "qsv_h265",
    "Level": "auto",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "balanced",
    "Profile": "auto",
    "Quality": 20,
    "QSV": {
      "Decode": false
    }
  }
}
[08:39:46] CPU: Intel(R) Core(TM) i7-8559U CPU @ 2.70GHz
[08:39:46]  - Intel microarchitecture Kaby Lake
[08:39:46]  - logical processor count: 8
[08:39:46] Intel Quick Sync Video support: yes, gpu list: 0
[08:39:46] Intel Quick Sync Video integrated adapter with index 0
[08:39:46]  - Intel Media SDK hardware: API 1.34 (minimum: 1.3)
[08:39:46]  - Decode support:  h264 hevc (8bit: yes, 10bit: yes)
[08:39:46]  - H.264 encoder: yes
[08:39:46]     - preferred implementation: hardware (1) via D3D11
[08:39:46]     - capabilities (hardware):  breftype icq+la+i+downs vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice
[08:39:46]  - H.265 encoder: yes (8bit: yes, 10bit: yes)
[08:39:46]     - preferred implementation: hardware (1) via D3D11
[08:39:46]     - capabilities (hardware):  bpyramid icq vsinfo opt1
[08:39:46] hb_scan: path=C:\Users\tvill\Desktop\Burn\backup\MADAGASCAR, title_index=1
src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\
src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library
src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\
src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library
[08:39:46] scan: BD has 65 title(s)
[08:39:46] bd: scanning title 1
[08:39:46] bd: playlist 00000.MPLS
[08:39:46] bd: duration is 01:25:54 (5154774 ms)
[08:39:46] bd: video id=0x1011, stream type=H.264, format 1080p
[08:39:46] bd: aspect = 16:9
[08:39:46] bd: audio id=0x761100, lang=English (AC3), 3cc=eng
[08:39:46] bd: audio id=0x721100, lang=English (TrueHD), 3cc=eng
[08:39:46] bd: audio id=0x1101, lang=Francais (AC3), 3cc=fra
[08:39:46] bd: audio id=0x1102, lang=espaol (AC3), 3cc=spa
[08:39:46] bd: audio id=0x1103, lang=Portugues (AC3), 3cc=por
[08:39:46] bd: audio id=0x1104, lang=English (AC3), 3cc=eng
[08:39:46] bd: subtitle id=0x1200, lang=English [PGS], 3cc=eng
[08:39:46] bd: subtitle id=0x1201, lang=English [PGS], 3cc=eng
[08:39:46] bd: subtitle id=0x1202, lang=Francais [PGS], 3cc=fra
[08:39:46] bd: subtitle id=0x1203, lang=espaol [PGS], 3cc=spa
[08:39:46] bd: subtitle id=0x1204, lang=Portugues [PGS], 3cc=por
[08:39:46] bd: subtitle id=0x1209, lang=English [PGS], 3cc=eng
[08:39:46] bd: subtitle id=0x1205, lang=English [PGS], 3cc=eng
[08:39:46] bd: subtitle id=0x1206, lang=Francais [PGS], 3cc=fra
[08:39:46] bd: subtitle id=0x1207, lang=espaol [PGS], 3cc=spa
[08:39:46] bd: subtitle id=0x1208, lang=Portugues [PGS], 3cc=por
[08:39:46] bd: chap 1, 333124 ms
[08:39:46] bd: chap 2, 180972 ms
[08:39:46] bd: chap 3, 245328 ms
[08:39:46] bd: chap 4, 316107 ms
[08:39:46] bd: chap 5, 275984 ms
[08:39:46] bd: chap 6, 263721 ms
[08:39:46] bd: chap 7, 242075 ms
[08:39:46] bd: chap 8, 272146 ms
[08:39:46] bd: chap 9, 275733 ms
[08:39:46] bd: chap 10, 268184 ms
[08:39:46] bd: chap 11, 257590 ms
[08:39:46] bd: chap 12, 206497 ms
[08:39:46] bd: chap 13, 298548 ms
[08:39:46] bd: chap 14, 299299 ms
[08:39:46] bd: chap 15, 279821 ms
[08:39:46] bd: chap 16, 340590 ms
[08:39:46] bd: chap 17, 222972 ms
[08:39:46] bd: chap 18, 118576 ms
[08:39:46] bd: chap 19, 37120 ms
[08:39:46] bd: chap 20, 420378 ms
[08:39:46] bd: title 1 has 20 chapters
[08:39:46] scan: decoding previews for title 1
[08:39:46] scan: title angle(s) 1
[08:39:46] scan: audio 0x721100: truehd, rate=48000Hz, bitrate=128000 English (TrueHD) (5.1 ch) (128 kbps)
[08:39:46] scan: audio 0x1104: ac3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch) (Dolby Surround) (192 kbps)
[08:39:46] scan: audio 0x761100: ac3, rate=48000Hz, bitrate=640000 English (AC3) (5.1 ch) (640 kbps)
[08:39:46] scan: audio 0x1101: ac3, rate=48000Hz, bitrate=640000 Francais (AC3) (5.1 ch) (640 kbps)
[08:39:46] scan: audio 0x1102: ac3, rate=48000Hz, bitrate=640000 espaol (AC3) (5.1 ch) (640 kbps)
[08:39:46] scan: audio 0x1103: ac3, rate=48000Hz, bitrate=640000 Portugues (AC3) (5.1 ch) (640 kbps)
[08:39:48] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1, color profile: 1-1-1
[08:39:48] scan: supported video decoders: avcodec qsv
[08:39:48] stream: 10 good frames, 0 errors (0%)
[08:39:48] libhb: scan thread found 1 valid title(s)
[08:39:48] Starting Task: Encoding Pass
[08:39:48] Skipping crop/scale filter
[08:39:48] job configuration:
[08:39:48]  * source
[08:39:48]    + C:\Users\tvill\Desktop\Burn\backup\MADAGASCAR
[08:39:48]    + title 1, chapter(s) 1 to 20
[08:39:48]  * destination
[08:39:48]    + E:\OneDrive\Movies\Madagascar.mkv
[08:39:48]    + container: Matroska (libavformat)
[08:39:48]      + chapter markers
[08:39:48]  * video track
[08:39:48]    + decoder: h264 8-bit
[08:39:48]      + bitrate 200 kbps
[08:39:48]    + filters
[08:39:48]      + 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)
[08:39:48]      + Decomb (mode=39)
[08:39:48]      + Framerate Shaper (mode=1)
[08:39:48]        + frame rate: 23.976 fps -> constant 23.976 fps
[08:39:48]    + Output geometry
[08:39:48]      + storage dimensions: 1920 x 1080
[08:39:48]      + pixel aspect ratio: 1 : 1
[08:39:48]      + display dimensions: 1920 x 1080
[08:39:48]    + encoder: H.265 (Intel Media SDK)
[08:39:48]      + preset:  balanced
[08:39:48]      + profile: auto
[08:39:48]      + level:   auto
[08:39:48]      + quality: 20.00 (ICQ)
[08:39:48]      + color profile: 1-1-1
[08:39:48]  * audio track 1
[08:39:48]    + decoder: English (AC3) (5.1 ch) (640 kbps) (track 1, id 0x761100)
[08:39:48]      + bitrate: 640 kbps, samplerate: 48000 Hz
[08:39:48]    + AC3 Passthru
[08:39:48]  * audio track 2
[08:39:48]    + decoder: English (TrueHD) (5.1 ch) (128 kbps) (track 2, id 0x721100)
[08:39:48]      + bitrate: 128 kbps, samplerate: 48000 Hz
[08:39:48]    + TrueHD Passthru
src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\
src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library
src/libbluray/bdj/bdj.c:133: Error opening registry key SOFTWARE\JavaSoft\Java Runtime Environment\
src/libbluray/bdj/bdj.c:746: BD-J check: Failed to load JVM library
[08:39:48] sync: expecting 123590 video frames
[08:39:48] qsv: integrated qsv adapter with index 0 has been selected
[08:39:48] encqsvInit: using encode-only path
[08:39:48] encqsvInit: H.265/HEVC Main profile @ level (null)
[08:39:48] encqsvInit: TargetUsage 4 AsyncDepth 4
[08:39:48] encqsvInit: GopRefDist 3 GopPicSize 24 NumRefFrame 2
[08:39:48] encqsvInit: BFramesMax 2 BRefType off
[08:39:48] encqsvInit: RateControlMethod ICQ ICQQuality 20
[08:39:48] sync: first pts audio 0x721100 is 0
[08:39:48] sync: first pts audio 0x761100 is 0
[08:39:48] sync: first pts video is 0
[08:39:48] sync: "Chapter 1" (1) at frame 1 time 0
[08:39:48] qsv_enc_init: using 'hardware (1) via D3D11' implementation, API: 1.34
[08:44:16] sync: "Chapter 2" (2) at frame 7979 time 29947417
Error code -21, hb_qsv_wait_on_sync 607
encqsv: hb_qsv_wait_on_sync failed (-21)
encqsvWork: qsv_enc_work failed -21
[08:45:39] work: average encoding speed for job is 29.369781 fps
[08:45:39] comb detect: heavy 29 | light 291 | uncombed 9988 | total 10308
[08:45:39] decomb: deinterlaced 29 | blended 291 | unfiltered 9984 | total 10304
[08:45:40] vfr: 10300 frames output, 0 dropped and 0 duped for CFR/PFR
[08:45:40] vfr: lost time: 0 (0 frames)
[08:45:40] vfr: gained time: 0 (0 frames) (0 not accounted for)
[08:45:40] stream: 10364 good frames, 0 errors (0%)
[08:45:40] ac3-decoder done: 13468 frames, 0 decoder errors
[08:45:40] truehd-decoder done: 517210 frames, 0 decoder errors
[08:45:40] h264-decoder done: 10342 frames, 0 decoder errors
[08:45:40] sync: got 10309 frames, 123590 expected
[08:45:40] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[08:45:40] mux: track 0, 10284 frames, 377276970 bytes, 7004.63 kbps, fifo 256
[08:45:40] mux: track 1, 13438 frames, 34401280 bytes, 638.70 kbps, fifo 512
[08:45:40] mux: track 2, 517010 frames, 188218820 bytes, 3494.52 kbps, fifo 16384
[08:45:40] Finished work at: Sat May 08 08:45:40 2021
[08:45:40] libhb: work result = 4

# Job Failed (4)
maximd33 commented 3 years ago

@tvillini thanks for clarification

can you pls try Big Buck Bunny as input, freely available from https://download.blender.org/demo/movies/BBB/ (or any other free input clips)

this will allow us to reproduce the same setup on our side

tvillini commented 3 years ago

@maximd33 Thank you for the assist. I'm starting the download now, and it shows it at 12 hours so it'll probably go overnight and I'll get at it tomorrow morning. Thank you!

tvillini commented 3 years ago

Got the file down and ran the transcode on it. Same issue.

05.09.2021 11-40-02_encode_Bbb Sunflower 1080P 60Fps Normal.txt

mikk9 commented 3 years ago

Can you try with low power mode on? And try using CQP, VBR, CBR since ICQ low power isn't fully supported in current drivers.

sr55 commented 3 years ago

Got a source for that @mikk9 ?

mikk9 commented 3 years ago

Yes here: https://github.com/Intel-Media-SDK/MediaSDK/issues/1977#issuecomment-876604607

sr55 commented 3 years ago

Thanks for that!

tvillini commented 3 years ago

In the time between I posted, and now… I discovered The problem could have been overheating. In June, A fan gave out, and the box wouldn’t boot.

I replaced with an M1 Mac Mini.

It’s… fast.

mikk9 commented 3 years ago

Just wanted to say that with driver 27.20.100.9749 ICQ low power has been fully enabled (on Tigerlake) for the first time. Build 9749 and newer should be fine, although I can't test on RKL.