Handbrake influences GPU Clock

[CD82]

Please detail your question or problem in as much detail as possible:
Problem 1: With MSI Afterburner running, Handbranke won't start.
Problem 2: When Handbrake starts, it puts my GPU (GTX 1080 ti) out of its sleep state and into max base clock (around 1.6 GHz). The encoding is still executed on the CPU which shows 100% load, while the GPU shows 0% load but for some reason it jumps to its max base clock when Handbrake is running (just the main window, not even encoding in process). In this idle, but high frequency state, the graphics card uses 60 W instead of 10 W.

Tell us about your environment. What version of HandBrake? What version of Windows are you running. etc.
- Handbrake version 1.07
- Windows 10, Version 1703 (Build 15063.138)
- i7 7700K, 16 GB memory, GTX 1080 ti
- Omen X 35, G-Sync enabled in Full Screen Applications only

If there was any exception or error displayed, please copy it and paste it here:
No errors displayed. With MSI Afterburner running, Handbrake simply won't start.

Please include the scan or encode log:

Code: Select all

HandBrake 1.0.7 (2017040900) - 64bit
OS: Microsoft Windows NT 10.0.15063.0 - 64bit
CPU: Intel(R) Core(TM) i7-7700K CPU @ 4.20GHz
Ram: 16314 MB, 
GPU Information:
  NVIDIA GeForce GTX 1080 Ti - 22.21.13.8165
Screen: 3440x1440
Temp Dir: C:\XXXXXXXX\Local\Temp\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\XXXXXXXX\HandBrake Team\HandBrake\1.0.7.0

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


# Starting Encode ...

[14:12:45] hb_init: starting libhb thread
[14:12:45] 1 job(s) to process
[14:12:45] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0.0,
        "Encoder": 1074003968,
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 1,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "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"
      }
    ],
    "ChapterMarkers": true,
    "File": "X:\\XXXXXXXX.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 2097152
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 11,
        "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": 13
    },
    "Title": 181,
    "Path": "F:\\MakeMKV Storage\\backup\\XXXXXXXX"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": true,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": [
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 1,
        "Offset": 0,
        "Track": 0
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 2,
        "Offset": 0,
        "Track": 1
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 3,
        "Offset": 0,
        "Track": 2
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 4,
        "Offset": 0,
        "Track": 3
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 5,
        "Offset": 0,
        "Track": 4
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 6,
        "Offset": 0,
        "Track": 5
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 7,
        "Offset": 0,
        "Track": 6
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 8,
        "Offset": 0,
        "Track": 7
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 9,
        "Offset": 0,
        "Track": 8
      },
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 10,
        "Offset": 0,
        "Track": 9
      }
    ]
  },
  "Video": {
    "Encoder": 65536,
    "Level": "4.0",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "ref=5:bframes=5",
    "Preset": "veryslow",
    "Profile": "high",
    "Quality": 20.0,
    "OpenCL": false,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[14:12:45] CPU: Intel(R) Core(TM) i7-7700K CPU @ 4.20GHz
[14:12:45]  - Intel microarchitecture Kaby Lake
[14:12:45]  - logical processor count: 8
[14:12:45] Intel Quick Sync Video support: no
[14:12:45] hb_scan: path=F:\MakeMKV Storage\backup\XXXXXXXX, title_index=181
[14:12:45] scan: BD has 183 title(s)
[14:12:45] bd: scanning title 181
[14:12:45] bd: playlist 00802.MPLS
[14:12:45] bd: duration is 00:54:14 (3254292 ms)
[14:12:45] bd: video id=0x1011, stream type=H.264, format 1080p
[14:12:45] bd: aspect = 16:9
[14:12:45] bd: audio id=0x711100, lang=English (DTS), 3cc=eng
[14:12:45] bd: audio id=0x1100, lang=English (DTS-HD MA), 3cc=eng
[14:12:45] bd: audio id=0x1101, lang=Francais (DTS), 3cc=fra
[14:12:45] bd: audio id=0x1102, lang=español (DTS), 3cc=spa
[14:12:45] bd: audio id=0x1103, lang=Deutsch (DTS), 3cc=deu
[14:12:45] bd: audio id=0x1104, lang=italiano (DTS), 3cc=ita
[14:12:45] bd: subtitle id=0x1200, lang=English, 3cc=eng
[14:12:45] bd: subtitle id=0x1201, lang=Francais, 3cc=fra
[14:12:45] bd: subtitle id=0x1202, lang=español, 3cc=spa
[14:12:45] bd: subtitle id=0x1203, lang=dansk, 3cc=dan
[14:12:45] bd: subtitle id=0x1204, lang=Nederlands, 3cc=nld
[14:12:45] bd: subtitle id=0x1205, lang=suomi, 3cc=fin
[14:12:45] bd: subtitle id=0x1206, lang=Deutsch, 3cc=deu
[14:12:45] bd: subtitle id=0x1207, lang=italiano, 3cc=ita
[14:12:45] bd: subtitle id=0x1208, lang=norsk, 3cc=nor
[14:12:45] bd: subtitle id=0x1209, lang=svenska, 3cc=swe
[14:12:45] bd: chap 1 packet=768, 173298 ms
[14:12:45] bd: chap 2 packet=737499264, 242575 ms
[14:12:45] bd: chap 3 packet=1595413440, 274649 ms
[14:12:45] bd: chap 4 packet=2718672960, 143560 ms
[14:12:45] bd: chap 5 packet=3224008128, 190982 ms
[14:12:45] bd: chap 6 packet=3935323776, 328870 ms
[14:12:45] bd: chap 7 packet=5157802944, 334625 ms
[14:12:45] bd: chap 8 packet=6469262208, 338046 ms
[14:12:45] bd: chap 9 packet=7462594752, 360026 ms
[14:12:45] bd: chap 10 packet=8730241920, 311269 ms
[14:12:45] bd: chap 11 packet=9944994624, 306097 ms
[14:12:45] bd: chap 12 packet=11109295872, 250041 ms
[14:12:45] bd: chap 13 packet=11984228160, 250 ms
[14:12:45] bd: title 181 has 13 chapters
[14:12:45] scan: decoding previews for title 181
[14:12:45] scan: title angle(s) 1
[14:12:45] scan: audio 0x711100: dca, rate=48000Hz, bitrate=1536000 English (DTS) (5.1 ch)
[14:12:45] scan: audio 0x1100: dca, rate=48000Hz, bitrate=1536000 English (DTS-HD MA) (5.1 ch)
[14:12:45] scan: audio 0x1101: dca, rate=48000Hz, bitrate=768000 Francais (DTS) (5.1 ch)
[14:12:45] scan: audio 0x1102: dca, rate=48000Hz, bitrate=768000 español (DTS) (5.1 ch)
[14:12:45] scan: audio 0x1103: dca, rate=48000Hz, bitrate=768000 Deutsch (DTS) (5.1 ch)
[14:12:45] scan: audio 0x1104: dca, rate=48000Hz, bitrate=768000 italiano (DTS) (5.1 ch)
[14:12:46] scan: 10 previews, 1920x1080, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[14:12:46] scan: supported video decoders: avcodec qsv
[14:12:46] stream: 10 good frames, 0 errors (0%)
[14:12:46] libhb: scan thread found 1 valid title(s)
[14:12:46] starting job
[14:12:46] job configuration:
[14:12:46]  * source
[14:12:46]    + F:\MakeMKV Storage\backup\XXXXXXXX
[14:12:46]    + title 181, chapter(s) 1 to 13
[14:12:46]  * destination
[14:12:46]    + X:\XXXXXXXX.mkv
[14:12:46]    + container: Matroska (libavformat)
[14:12:46]      + chapter markers
[14:12:46]  * video track
[14:12:46]    + decoder: h264
[14:12:46]      + bitrate 200 kbps
[14:12:46]    + filters
[14:12:46]      + Framerate Shaper (mode=1)
[14:12:46]        + frame rate: 23.976 fps -> constant 23.976 fps
[14:12:46]      + Crop and Scale (width=1920:height=1080:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[14:12:46]        + source: 1920 * 1080, crop (0/0/0/0): 1920 * 1080, scale: 1920 * 1080
[14:12:46]    + Output geometry
[14:12:46]      + storage dimensions: 1920 x 1080
[14:12:46]      + pixel aspect ratio: 1 : 1
[14:12:46]      + display dimensions: 1920 x 1080
[14:12:46]  * Foreign Audio Search: Passthrough, Forced Only, Default
[14:12:46]    + subtitle, English (track 0, id 0x1200) Picture [PGS]
[14:12:46] sync: expecting 78024 video frames
[14:12:59] reader: done. 1 scr changes
[14:12:59] work: average encoding speed for job is 0.000000 fps
[14:12:59] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[14:12:59] vfr: lost time: 0 (0 frames)
[14:12:59] vfr: gained time: 0 (0 frames) (0 not accounted for)
[14:12:59] stream: 78025 good frames, 0 errors (0%)
[14:12:59] sync: got 0 frames, 78024 expected
[14:12:59] Subtitle track 0 (id 0x1200) 'English': 571 hits (0 forced)
[14:12:59] No candidate detected during subtitle scan
[14:12:59] starting job
[14:12:59] job configuration:
[14:12:59]  * source
[14:12:59]    + F:\MakeMKV Storage\backup\XXXXXXXX
[14:12:59]    + title 181, chapter(s) 1 to 13
[14:12:59]  * destination
[14:12:59]    + X:\XXXXXXXX.mkv
[14:12:59]    + container: Matroska (libavformat)
[14:12:59]      + chapter markers
[14:12:59]  * video track
[14:12:59]    + decoder: h264
[14:12:59]      + bitrate 200 kbps
[14:12:59]    + filters
[14:12:59]      + Framerate Shaper (mode=1)
[14:12:59]        + frame rate: 23.976 fps -> constant 23.976 fps
[14:12:59]      + Crop and Scale (width=1920:height=1080:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[14:12:59]        + source: 1920 * 1080, crop (0/0/0/0): 1920 * 1080, scale: 1920 * 1080
[14:12:59]    + Output geometry
[14:12:59]      + storage dimensions: 1920 x 1080
[14:12:59]      + pixel aspect ratio: 1 : 1
[14:12:59]      + display dimensions: 1920 x 1080
[14:12:59]    + encoder: H.264 (libx264)
[14:12:59]      + preset:  veryslow
[14:12:59]      + options: ref=5:bframes=5
[14:12:59]      + profile: high
[14:12:59]      + level:   4.0
[14:12:59]      + quality: 20.00 (RF)
[14:12:59]  * subtitle track 1, English (track 0, id 0x1200) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 2, Francais (track 1, id 0x1201) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 3, español (track 2, id 0x1202) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 4, dansk (track 3, id 0x1203) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 5, Nederlands (track 4, id 0x1204) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 6, suomi (track 5, id 0x1205) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 7, Deutsch (track 6, id 0x1206) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 8, italiano (track 7, id 0x1207) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 9, norsk (track 8, id 0x1208) Picture [PGS] -> Passthrough
[14:12:59]  * subtitle track 10, svenska (track 9, id 0x1209) Picture [PGS] -> Passthrough
[14:12:59]  * audio track 1
[14:12:59]    + decoder: English (DTS-HD MA) (5.1 ch) (track 2, id 0x1100)
[14:12:59]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[14:12:59]    + DTS-HD Passthru
[14:12:59] sync: expecting 78024 video frames
[14:12:59] encx264: min-keyint: 24, keyint: 240
[14:12:59] encx264: encoding at constant RF 20.000000
[14:12:59] encx264: unparsed options: ref=4:direct=auto:b-adapt=2:bframes=5:vbv-maxrate=25000:subme=10:me=umh:vbv-bufsize=31250:level=4.0:analyse=all:rc-lookahead=60:merange=24:trellis=2
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2
x264 [info]: profile High, level 4.0
[14:12:59] sync: first pts video is 0
[14:12:59] sync: "Chapter 1" (1) at frame 1 time 0
[14:12:59] sync: first pts audio 0x1100 is 0
[14:13:00] sync: first pts subtitle 0x1202 is 236486
[14:13:00] sync: first pts subtitle 0x1203 is 236486
[14:13:00] sync: first pts subtitle 0x1204 is 236486
[14:13:00] sync: first pts subtitle 0x1201 is 240240
[14:13:00] sync: first pts subtitle 0x1205 is 240240
[14:13:00] sync: first pts subtitle 0x1206 is 240240
[14:13:00] sync: first pts subtitle 0x1208 is 240240
[14:13:00] sync: first pts subtitle 0x1207 is 243993
[14:13:00] sync: first pts subtitle 0x1209 is 243993
[14:13:00] sync: first pts subtitle 0x1200 is 259008
[14:19:36] sync: "Chapter 2" (2) at frame 4156 time 15596831
[14:27:14] sync: "Chapter 3" (3) at frame 9972 time 37428641
[14:36:42] sync: "Chapter 4" (4) at frame 16557 time 62147085
[14:40:31] sync: "Chapter 5" (5) at frame 19999 time 75067492
[14:46:55] sync: "Chapter 6" (6) at frame 24578 time 92255913
[14:56:43] sync: "Chapter 7" (7) at frame 32463 time 121854232
[15:07:40] sync: "Chapter 8" (8) at frame 40486 time 151970568
[15:16:38] sync: "Chapter 9" (9) at frame 48591 time 182394712
[15:27:41] sync: "Chapter 10" (10) at frame 57223 time 214797082
[15:37:50] sync: "Chapter 11" (11) at frame 64686 time 242811318
[15:47:44] sync: "Chapter 12" (12) at frame 72025 time 270360090
[15:55:00] reader: done. 1 scr changes
[15:55:04] work: average encoding speed for job is 12.738382 fps
[15:55:04] vfr: 78025 frames output, 0 dropped and 1 duped for CFR/PFR
[15:55:04] vfr: lost time: 0 (0 frames)
[15:55:04] vfr: gained time: 0 (0 frames) (0 not accounted for)
[15:55:04] stream: 78025 good frames, 0 errors (0%)
[15:55:04] dca-decoder done: 305089 frames, 0 decoder errors
[15:55:04] h264-decoder done: 78024 frames, 0 decoder errors
[15:55:04] sync: got 78024 frames, 78024 expected
[15:55:04] sync: framerate min 11.987 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:570   Avg QP:16.48  size:172168
x264 [info]: frame P:20915 Avg QP:20.20  size: 67342
x264 [info]: frame B:56540 Avg QP:21.42  size: 35330
x264 [info]: consecutive B-frames:  1.8%  2.8% 39.5% 13.7% 21.9% 20.4%
x264 [info]: mb I  I16..4: 26.1% 70.6%  3.3%
x264 [info]: mb P  I16..4:  9.5% 27.5%  0.7%  P16..4: 42.9%  6.8%  5.3%  0.0%  0.0%    skip: 7.3%
x264 [info]: mb B  I16..4:  4.6%  5.7%  0.3%  B16..8: 38.6%  4.1%  0.9%  direct: 7.7%  skip:38.1%  L0:47.7% L1:45.9% BI: 6.4%
x264 [info]: 8x8 transform intra:65.1% inter:74.6%
x264 [info]: direct mvs  spatial:99.9% temporal:0.1%
x264 [info]: coded y,uvDC,uvAC intra: 78.5% 85.2% 70.9% inter: 19.9% 31.9% 9.9%
x264 [info]: i16 v,h,dc,p: 10%  7% 58% 25%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 14% 11% 30%  7%  7%  8%  8%  8%  9%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16%  9% 31%  6%  8%  9%  8%  6%  7%
x264 [info]: i8c dc,h,v,p: 68% 14% 12%  6%
x264 [info]: Weighted P-Frames: Y:1.4% UV:1.0%
x264 [info]: ref P L0: 59.6%  7.7% 21.9% 10.5%  0.2%  0.0%
x264 [info]: ref B L0: 82.6% 14.1%  3.3%
x264 [info]: ref B L1: 95.3%  4.7%
x264 [info]: kb/s:8614.22
[15:55:07] mux: track 0, 78025 frames, 3504127510 bytes, 8614.06 kbps, fifo 512
[15:55:07] mux: track 1, 305089 frames, 1511902468 bytes, 3716.65 kbps, fifo 4096
[15:55:07] mux: track 2, 1142 frames, 14659983 bytes, 36.04 kbps, fifo 16
[15:55:07] mux: track 3, 1058 frames, 14184190 bytes, 34.87 kbps, fifo 16
[15:55:07] mux: track 4, 1041 frames, 12771689 bytes, 31.40 kbps, fifo 16
[15:55:07] mux: track 5, 1060 frames, 14317588 bytes, 35.20 kbps, fifo 16
[15:55:07] mux: track 6, 990 frames, 11121321 bytes, 27.34 kbps, fifo 16
[15:55:07] mux: track 7, 1060 frames, 14529014 bytes, 35.72 kbps, fifo 16
[15:55:07] mux: track 8, 1046 frames, 15319265 bytes, 37.66 kbps, fifo 16
[15:55:07] mux: track 9, 1056 frames, 14080204 bytes, 34.61 kbps, fifo 16
[15:55:07] mux: track 10, 1062 frames, 12377034 bytes, 30.43 kbps, fifo 16
[15:55:07] mux: track 11, 1042 frames, 12890796 bytes, 31.69 kbps, fifo 16
[15:55:07] libhb: work result = 0

# Encode Completed ...

Since Handbrake is not starting when Afterburner is running, and the fact that Handbrake causes the graphics card to go from its idle state to its max base clock (without putting any actual load on the GPU), I think that Handbrake is somehow trying to do stuff to my GPU. As soon as I close Handbrake, the GPU returns to its normal idle clock of 150 MHz.

I used to run Handbranke on a Windows 7 computer and never observed this behaviour. Handbrake always started with Afterburner running in the background, and it did not influence the GPU clock in any way (the GPU stayed at its lowest possible clock speed during encodings).

[mduell]

Are you using GPU accelerated decoding?

If only you posted the REQUIRED encoding log so we can see what you're actually doing...

[CD82]

Added encoding log in original post. It's a fresh install of Handbrake with the default settings. The GPU is at 0% load, its clock is just going up to max whenever Handbrake is running. It's not actually being used for anything:

[s55]

Does the act of just opening HandBrake cause the GPU to scale up? Or do you need to scan or encode first?


The Msi afterburner / rivatuner is a driver / OS Bug introduced after creators update. Not something we have control over.

[CD82]

Just opening Handbrake is enough. As soon as the main window appears, the gpu and vram clocks jump to max values, but the activity on the gpu is 0%.

I can work around the other issue, disabling Afterburner/Rivatuner lets me open Handbrake. So that's sort of alright. But having the GPU sit at max clock for the whole encoding process without actually doing anything seems kind of a waste to me.

[rollin_eng]

If the activity is 0% then its not doing or wasting anything apart from some minimal power draw.

[s55]

60W is quite substantial at idle.

Also, I just checked. It appears to be a Nvidia Driver issue as well. Both AMD and Intel clock shift correctly. The HandBrake GUI uses WPF which is GPU rendered, so that may trigger it to increase but we have no control over that.

So, I'm afraid the answer to both of your problems is down to Drivers/OS problems.

[CD82]

I see. Thanks for the quick response.

[CD82]

I found the culprit:

Nvidia Control Panel -> Manage 3D Settings -> Power Management Mode

This was set to "maximum performance" which caused the GPU to randomly leave its idle state, even in Windows with nothing going on.

Now I set it to "optimal power", and the GPU stays at its lowest clock speed, even when Handbrake is running.

Apparently just having Handbrake open was enough for the driver to put the GPU in almost full gear with "maximum performance" power management. Although there was basically no load on the GPU (according to HWiNFO the GPU had 0% load at 1.5 GHz). Now that it's running at a mucher lower frequency, there is actually a fraction of a load being put on the GPU when Handbrake is running (around 3% at 150 MHz). This tiny load probably caused the driver to switch to a much higher frequency, but then of course the fractional load became so tiny that it was being rounded down to 0%.