Handbrake is unexpectedly slow on Server 2008 R2

HandBrake for Windows support
Forum rules
An Activity Log is required for support requests. Please read How-to get an activity log? for details on how and why this should be provided.
Post Reply
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

Description of problem or question:

Running Handbrake on Server 2008 R2 SP1 the speed is significantly worse than running on Windows 7

Steps to reproduce the problem (If Applicable):

Choose any video and resample on both Server 2008 R2 SP1 and Windows 7 SP1 on identical hardware. Get the processing time from the logs and the time will be around 40% longer on Server 2008.

HandBrake version (e.g., 1.0.0):

HandBrake 1.1.0 (2018040700) - 64bit

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

Windows Server 2008 R2 SP1
Windows 7 SP1

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

Logs from both OSs are appended. NB my copy of Win7 is 32 bit so I had to use v1.0.7. But it is still faster than Server 208 even though it's an older version of Handbrake.

The only difference I can see is that OpenCL is not available on Server 2008. Could this be the reason?

This is the Server 2008 log

Code: Select all

HandBrake 1.1.0 (2018040700) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Xeon(R) CPU E3-1280 V2 @ 3.60GHz
Ram: 8161 MB, 
GPU Information:
  Matrox G200eW (Winbond) - 1.1.3.0
Screen: 1024x768
Temp Dir: C:\Users\Administrator\AppData\Local\Temp\2\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake Team\HandBrake\1.1.0.0

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


# Starting Encode ...

[06:20:44] hb_init: starting libhb thread
[06:20:44] 1 job(s) to process
[06:20:44] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 6,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": false,
    "File": "E:\\temp\\Sausage Party-1.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "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": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "384",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 1,
    "Path": "E:\\temp\\Handbrake\\Sausage Party.avi"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 21.0,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[06:20:44] CPU: Intel(R) Xeon(R) CPU E3-1280 V2 @ 3.60GHz
[06:20:44]  - Intel microarchitecture Ivy Bridge
[06:20:44]  - logical processor count: 8
[06:20:44] Intel Quick Sync Video support: no
[06:20:44] hb_scan: path=E:\temp\Handbrake\Sausage Party.avi, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image E:\temp\Handbrake\Sausage Party.avi
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
[06:20:44] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
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
[06:20:44] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'E:\temp\Handbrake\Sausage Party.avi':
  Duration: 01:28:45.24, start: 0.000000, bitrate: 2236 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) [XVID / 0x44495658]
      yuv420p, 720x384 [PAR 1:1 DAR 15:8]
      23.98 fps, 23.98 tbn
    Stream #0:1: Audio: ac3 [[0] [0][0] / 0x2000]
      48000 Hz, 5.1, fltp, 448 kb/s
[06:20:45] scan: decoding previews for title 1
[06:20:45] scan: audio 0x1: ac3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[06:20:45] scan: 10 previews, 720x384, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[06:20:45] libhb: scan thread found 1 valid title(s)
[06:20:45] starting job
[06:20:45] work: track 1, dithering not supported by codec
[06:20:45] work: only 1 chapter, disabling chapter markers
[06:20:45] job configuration:
[06:20:45]  * source
[06:20:45]    + E:\temp\Handbrake\Sausage Party.avi
[06:20:45]    + title 1, chapter(s) 1 to 1
[06:20:45]    + container: avi
[06:20:45]    + data rate: 2236 kbps
[06:20:45]  * destination
[06:20:45]    + E:\temp\Sausage Party-1.m4v
[06:20:45]    + container: MPEG-4 (libavformat)
[06:20:45]  * video track
[06:20:45]    + decoder: mpeg4
[06:20:45]    + filters
[06:20: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)
[06:20:45]      + Decomb (mode=39)
[06:20:45]      + Framerate Shaper (mode=2:rate=27000000/900000)
[06:20:45]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[06:20:45]      + Crop and Scale (width=720:height=384:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[06:20:45]        + source: 720 * 384, crop (0/0/0/0): 720 * 384, scale: 720 * 384
[06:20:45]    + Output geometry
[06:20:45]      + storage dimensions: 720 x 384
[06:20:45]      + pixel aspect ratio: 1 : 1
[06:20:45]      + display dimensions: 720 x 384
[06:20:45]    + encoder: H.264 (libx264)
[06:20:45]      + preset:  fast
[06:20:45]      + profile: main
[06:20:45]      + level:   3.1
[06:20:45]      + quality: 21.00 (RF)
[06:20:45]  * audio track 1
[06:20:45]    + decoder: Unknown (AC3) (5.1 ch) (track 1, id 0x1)
[06:20:45]      + bitrate: 448 kbps, samplerate: 48000 Hz
[06:20:45]    + mixdown: Dolby Pro Logic II
[06:20:45]    + encoder: AAC (libavcodec)
[06:20:45]      + bitrate: 160 kbps, samplerate: 48000 Hz
[06:20:45] decomb filter thread started for segment 0
[06:20:45] decomb filter thread started for segment 1
[06:20:45] decomb filter thread started for segment 2
[06:20:45] decomb check thread started for segment 4
[06:20:45] decomb check thread started for segment 6
[06:20:45] decomb filter thread started for segment 5
[06:20:45] decomb filter thread started for segment 7
[06:20:45] decomb filter thread started for segment 6
[06:20:45] mask filter thread started for segment 5
[06:20:45] decomb check thread started for segment 1
[06:20:45] mask erode thread started for segment 0
[06:20:45] decomb check thread started for segment 3
[06:20:45] mask dilate thread started for segment 2
[06:20:45] mask filter thread started for segment 0
[06:20:45] decomb filter thread started for segment 4
[06:20:45] mask filter thread started for segment 1
[06:20:45] decomb check thread started for segment 5
[06:20:45] mask dilate thread started for segment 4
[06:20:45] mask filter thread started for segment 2
[06:20:45] yadif thread started for segment 1
[06:20:45] yadif thread started for segment 2
[06:20:45] mask filter thread started for segment 4
[06:20:45] decomb check thread started for segment 0
[06:20:45] mask filter thread started for segment 6
[06:20:45] mask filter thread started for segment 7
[06:20:45] mask erode thread started for segment 1
[06:20:45] mask erode thread started for segment 2
[06:20:45] mask erode thread started for segment 3
[06:20:45] mask erode thread started for segment 4
[06:20:45] mask erode thread started for segment 5
[06:20:45] decomb check thread started for segment 2
[06:20:45] mask erode thread started for segment 6
[06:20:45] mask erode thread started for segment 7
[06:20:45] mask dilate thread started for segment 0
[06:20:45] mask dilate thread started for segment 1
[06:20:45] decomb filter thread started for segment 3
[06:20:45] mask dilate thread started for segment 3
[06:20:45] decomb check thread started for segment 7
[06:20:45] mask dilate thread started for segment 5
[06:20:45] mask dilate thread started for segment 6
[06:20:45] mask dilate thread started for segment 7
[06:20:45] yadif thread started for segment 0
[06:20:45] mask filter thread started for segment 3
[06:20:45] yadif thread started for segment 3
[06:20:45] yadif thread started for segment 4
[06:20:45] yadif thread started for segment 5
[06:20:45] yadif thread started for segment 6
[06:20:45] yadif thread started for segment 7
[06:20:46] sync: expecting 127678 video frames
[06:20:46] encx264: min-keyint: 24, keyint: 240
[06:20:46] encx264: encoding at constant RF 21.000000
[06:20:46] encx264: unparsed options: level=3.1:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=14000:vbv-maxrate=14000:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x264 [info]: profile Main, level 3.1
[06:20:46] sync: first pts video is 0
[06:20:46] sync: "Chapter 1" (1) at frame 1 time 0
[06:20:46] sync: first pts audio 0x1 is 0
[06:36:04] reader: done. 1 scr changes
[06:36:05] work: average encoding speed for job is 138.891708 fps
[06:36:05] comb detect: heavy 1306 | light 5806 | uncombed 120565 | total 127677
[06:36:05] decomb: deinterlaced 1306 | blended 5806 | unfiltered 120565 | total 127677
[06:36:05] vfr: 127677 frames output, 0 dropped and 0 duped for CFR/PFR
[06:36:05] vfr: lost time: 0 (0 frames)
[06:36:05] vfr: gained time: 0 (0 frames) (0 not accounted for)
[06:36:05] ac3-decoder done: 166413 frames, 0 decoder errors
[06:36:05] mpeg4-decoder done: 127678 frames, 0 decoder errors
[06:36:05] sync: got 127677 frames, 127678 expected
[06:36:05] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:1872  Avg QP:17.29  size: 25444
x264 [info]: frame P:44199 Avg QP:20.57  size:  7699
x264 [info]: frame B:81606 Avg QP:23.40  size:  2213
x264 [info]: consecutive B-frames:  5.0% 21.6% 23.3% 50.1%
x264 [info]: mb I  I16..4: 35.4%  0.0% 64.6%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.9%  P16..4: 36.0% 16.5%  8.7%  0.0%  0.0%    skip:19.6%
x264 [info]: mb B  I16..4:  2.5%  0.0%  1.6%  B16..8: 27.8%  7.3%  0.7%  direct: 7.2%  skip:53.0%  L0:39.5% L1:45.6% BI:14.9%
x264 [info]: coded y,uvDC,uvAC intra: 49.5% 73.7% 33.6% inter: 11.6% 22.4% 2.1%
x264 [info]: i16 v,h,dc,p: 34% 27% 18% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 25% 24% 18%  5%  6%  6%  6%  5%  5%
x264 [info]: i8c dc,h,v,p: 44% 25% 23%  8%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.0%
x264 [info]: ref P L0: 75.9% 24.1%
x264 [info]: ref B L0: 84.7% 15.3%
x264 [info]: ref B L1: 97.1%  2.9%
x264 [info]: kb/s:854.14
[06:36:05] mux: track 0, 127677 frames, 568496318 bytes, 854.04 kbps, fifo 4096
[06:36:05] mux: track 1, 249620 frames, 106699474 bytes, 160.29 kbps, fifo 8192
[06:36:05] libhb: work result = 0

# Encode Completed ...
This is the Windows 7 log

Code: Select all

HandBrake 1.0.7 (2017040900) - 32bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 32bit
CPU: Intel(R) Xeon(R) CPU E3-1280 V2 @ 3.60GHz
Ram: 3967 MB, 
GPU Information:
  Microsoft Virtual Machine Bus Video Device - 6.1.7601.17514
Screen: 1024x768
Temp Dir: C:\Users\renniej\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\renniej\AppData\Roaming\HandBrake Team\HandBrake\1.0.7.0

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


# Starting Encode ...

[07:18:48] hb_init: starting libhb thread
[07:18:48] 1 job(s) to process
[07:18:48] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 6,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "File": "C:\\rhs\\Video\\Sausage Party-1.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "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": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "384",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 1,
    "Path": "C:\\temp\\Handbrake\\Sausage Party.avi"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 21.0,
    "OpenCL": false,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[07:18:48] CPU: Intel(R) Xeon(R) CPU E3-1280 V2 @ 3.60GHz
[07:18:48]  - Intel microarchitecture Ivy Bridge
[07:18:48]  - logical processor count: 4
[07:18:48] Intel Quick Sync Video support: no
[07:18:48] hb_scan: path=C:\temp\Handbrake\Sausage Party.avi, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:274: failed opening UDF image C:\temp\Handbrake\Sausage Party.avi
src/libbluray/disc/disc.c:352: error opening file BDMV\index.bdmv
src/libbluray/disc/disc.c:352: error opening file BDMV\BACKUP\index.bdmv
[07:18:48] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.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
[07:18:48] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'C:\temp\Handbrake\Sausage Party.avi':
  Duration: 01:28:45.24, start: 0.000000, bitrate: 2236 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) [XVID / 0x44495658]
      yuv420p, 720x384 [PAR 1:1 DAR 15:8]
      23.98 fps, 23.98 tbn
    Stream #0:1: Audio: ac3 [[0] [0][0] / 0x2000]
      48000 Hz, 5.1, fltp, 448 kb/s
[07:18:48] scan: decoding previews for title 1
[07:18:48] scan: audio 0x1: ac3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[07:18:48] scan: 10 previews, 720x384, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[07:18:48] libhb: scan thread found 1 valid title(s)
[07:18:48] starting job
[07:18:48] decomb filter thread started for segment 0
[07:18:48] decomb filter thread started for segment 1
[07:18:48] decomb filter thread started for segment 3
[07:18:48] decomb check thread started for segment 1
[07:18:48] decomb check thread started for segment 0
[07:18:48] decomb check thread started for segment 2
[07:18:48] decomb filter thread started for segment 2
[07:18:48] mask filter thread started for segment 0
[07:18:48] mask filter thread started for segment 1
[07:18:48] mask filter thread started for segment 2
[07:18:48] mask filter thread started for segment 3
[07:18:48] mask erode thread started for segment 1
[07:18:48] mask erode thread started for segment 2
[07:18:48] mask erode thread started for segment 3
[07:18:48] mask dilate thread started for segment 1
[07:18:48] mask erode thread started for segment 0
[07:18:48] mask dilate thread started for segment 2
[07:18:48] mask dilate thread started for segment 3
[07:18:48] yadif thread started for segment 1
[07:18:48] mask dilate thread started for segment 0
[07:18:48] work: track 1, dithering not supported by codec
[07:18:48] yadif thread started for segment 3
[07:18:48] work: only 1 chapter, disabling chapter markers
[07:18:48] job configuration:
[07:18:48]  * source
[07:18:48]    + C:\temp\Handbrake\Sausage Party.avi
[07:18:48]    + title 1, chapter(s) 1 to 1
[07:18:48]    + container: avi
[07:18:48]    + data rate: 2236 kbps
[07:18:48]  * destination
[07:18:48]    + C:\rhs\Video\Sausage Party-1.m4v
[07:18:48]    + container: MPEG-4 (libavformat)
[07:18:48]  * video track
[07:18:48]    + decoder: mpeg4
[07:18:48]    + filters
[07:18:48] yadif thread started for segment 0
[07:18:48] yadif thread started for segment 2
[07:18: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)
[07:18:48]      + Decomb (mode=39)
[07:18:48]      + Framerate Shaper (mode=2:rate=27000000/900000)
[07:18:48]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[07:18:48]      + Crop and Scale (width=720:height=384:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[07:18:48]        + source: 720 * 384, crop (0/0/0/0): 720 * 384, scale: 720 * 384
[07:18:48]    + Output geometry
[07:18:48]      + storage dimensions: 720 x 384
[07:18:48]      + pixel aspect ratio: 1 : 1
[07:18:48]      + display dimensions: 720 x 384
[07:18:48]    + encoder: H.264 (libx264)
[07:18:48]      + preset:  fast
[07:18:48]      + profile: main
[07:18:48]      + level:   3.1
[07:18:48]      + quality: 21.00 (RF)
[07:18:48]  * audio track 1
[07:18:48]    + decoder: Unknown (AC3) (5.1 ch) (track 1, id 0x1)
[07:18:48]      + bitrate: 448 kbps, samplerate: 48000 Hz
[07:18:48]    + mixdown: Dolby Pro Logic II
[07:18:48]    + encoder: AAC (libavcodec)
[07:18:48]      + bitrate: 160 kbps, samplerate: 48000 Hz
[07:18:48] decomb check thread started for segment 3
[07:18:49] sync: expecting 127678 video frames
[07:18:49] encx264: min-keyint: 24, keyint: 240
[07:18:49] encx264: encoding at constant RF 21.000000
[07:18:49] encx264: unparsed options: level=3.1:vbv-maxrate=14000:vbv-bufsize=14000:ref=2:8x8dct=0:weightp=1:subme=6:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x264 [info]: profile Main, level 3.1
[07:18:49] sync: first pts video is 0
[07:18:49] sync: "Chapter 1" (1) at frame 1 time 0
[07:18:49] sync: first pts audio 0x1 is 0
[07:30:50] reader: done. 1 scr changes
[07:30:51] work: average encoding speed for job is 176.773788 fps
[07:30:51] comb detect: heavy 1306 | light 5806 | uncombed 120565 | total 127677
[07:30:51] decomb: deinterlaced 1306 | blended 5806 | unfiltered 120565 | total 127677
[07:30:51] vfr: 127677 frames output, 0 dropped and 0 duped for CFR/PFR
[07:30:51] vfr: lost time: 0 (0 frames)
[07:30:51] vfr: gained time: 0 (0 frames) (0 not accounted for)
[07:30:51] ac3-decoder done: 166413 frames, 0 decoder errors
[07:30:51] mpeg4-decoder done: 127678 frames, 0 decoder errors
[07:30:51] sync: got 127677 frames, 127678 expected
[07:30:51] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:1859  Avg QP:17.26  size: 25535
x264 [info]: frame P:43961 Avg QP:20.55  size:  7724
x264 [info]: frame B:81857 Avg QP:23.40  size:  2220
x264 [info]: consecutive B-frames:  4.7% 21.5% 23.4% 50.4%
x264 [info]: mb I  I16..4: 35.4%  0.0% 64.6%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.9%  P16..4: 36.0% 16.5%  8.8%  0.0%  0.0%    skip:19.6%
x264 [info]: mb B  I16..4:  2.5%  0.0%  1.6%  B16..8: 27.8%  7.3%  0.7%  direct: 7.2%  skip:52.9%  L0:39.5% L1:45.6% BI:15.0%
x264 [info]: coded y,uvDC,uvAC intra: 49.5% 73.7% 33.6% inter: 11.6% 22.4% 2.1%
x264 [info]: i16 v,h,dc,p: 34% 27% 18% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 25% 24% 18%  5%  6%  6%  6%  5%  5%
x264 [info]: i8c dc,h,v,p: 44% 25% 23%  8%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.0%
x264 [info]: ref P L0: 75.9% 24.1%
x264 [info]: ref B L0: 84.8% 15.2%
x264 [info]: ref B L1: 97.1%  2.9%
x264 [info]: kb/s:854.49
[07:30:51] mux: track 0, 127677 frames, 568729158 bytes, 854.39 kbps, fifo 4096
[07:30:51] mux: track 1, 249620 frames, 106699474 bytes, 160.29 kbps, fifo 8192
[07:30:51] libhb: work result = 0

# Encode Completed ...
mduell
Veteran User
Posts: 8187
Joined: Sat Apr 21, 2007 8:54 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by mduell »

Different core counts are detected, leading to different # of threads. Try normalizing the logical processor count.
rollin_eng
Veteran User
Posts: 4840
Joined: Wed May 04, 2011 11:06 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by rollin_eng »

Also one is read/writing to the c:\ and the other is using the e:\

Are these the same disk?
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

An update to this: the difference does not exist on Windows Server 2016, that is on Server 2016 and Windows 10 Handbrake runs at the same speed on identical hardware.

I guess speed on a ten year old server OS is not something of great concern, though I remain curious as to why the difference exists.

Re the two posts above, I don't have a machine I can use for testing 2008R2 and Win7 on the same box. However I've done lots of testing on a range of hardware and there is definitely a difference. Server 2008 R2 is consistently slower than Windows 7, and by quite a margin.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by s55 »

Your comparison above is completely invalid.

1. Your running Different versions of HandBrake (1.0.7 vs 1.1.0)
2. Your running a 32bit version of HandBrake on 1 machine, and 64bit on the other. (That's about 10% difference)
2. One of your machines is only showing up as 4 vs 8 cores, so in all likely hood, Hyperthreading is turned off, possibally even speedstep so your immediately taking 25~30% difference there.
3. One machine is software rendering the UI, one isn't. So you'll have additional overhead there. Potentially another 10~15% performance loss.

It doesn't matter what version of Windows you use. Same Source, Same Settings, Same Hardware = Same FPS within a margin of error.

I've run HandBrake on Server 2008 R2 and seen exactly the same performance as Windows 7, so it's down to your setup and how things are configured.
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

@s55 I take your points, but it's the Win7 machine with only four cores, software rendering and the older version of Handbrake that is the faster.

If it were slower then yes you'd have a point, but even though the Win7 machine is lower specced it's still faster than the Server2008 machine. That's what is hard to explain. Your statement:

>It doesn't matter what version of Windows you use. Same Source, Same Settings, Same Hardware = Same FPS within a margin of error.

is just plain wrong.
rollin_eng
Veteran User
Posts: 4840
Joined: Wed May 04, 2011 11:06 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by rollin_eng »

The problem is you are not running them on the same setup therefore you can’t compare the results.
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

If I get the time I will set up two identical machines with Server 2008 and Win7, but since I've found that the difference doesn't exist with Server 2016 and Win10 I'll probably just write it off as a peculiarity of Server 2008.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by s55 »

There has been plenty of benchmarks done over the years that show no discernible difference between any operating system: Mac, Windows, Windows Server or Linux.

The only time we've seen an issue was when there was an actual bug in the Linux Kernel causing problems.
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

@s55 if you think it's worthwhile then I'm willing to find some hardware and do tests with Server 2008 and Win7 on the same hardware. But

a) it's a lot of work installing Win7/2k8 on modern hardware - finding drivers and the hundreds of hotfixes that need installng

b) does anyone care about a ten year old server OS?
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by s55 »

Well, that's really up to you whether you care enough to track down what's up. It doesn't bother me an awful lot either way. All I'm saying, is it's not expected or seen in the wild behaviour so something is wrong.
mduell
Veteran User
Posts: 8187
Joined: Sat Apr 21, 2007 8:54 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by mduell »

jhsrennie wrote: Fri May 18, 2018 7:19 pm@s55 if you think it's worthwhile then I'm willing to find some hardware and do tests with Server 2008 and Win7 on the same hardware. But

a) it's a lot of work installing Win7/2k8 on modern hardware - finding drivers and the hundreds of hotfixes that need installng

b) does anyone care about a ten year old server OS?
If you actually make an apples to apples comparison and find a significant difference, there may be some interest.

Until then, very little interest in anything other than criticizing you.
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

It turns out the problem is having the Hyper-V role installed.

Without the Hyper-V role Handbrake runs at the same speed on Server 2008 R2 as it does on Windows 7 Pro. For completeness I tested on server 2016 and Windows 10 Pro, and Handbrake runs at the same speed on all four platforms. However if you install Hyper-V on Server 2008 then Handbrake slows to around half the speed. uninstall Hyper-V and it returns to its original speed.

However if you configure a VM on the server and run Handbrake in the VM then it runs at close to the speed that it runs on Windows 7 i.e. the VM runs Handbrake almost twice as fast as the host server does. Very odd.

I shall draw a line here. I can provide logs if anyone wants them, but I doubt they will be helpful since the logs look the same whether or not Hyper-V is installed.
Deleted User 13735

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by Deleted User 13735 »

There's a chance no one else is running your particular configuration, but glad you found a workaround.
mduell
Veteran User
Posts: 8187
Joined: Sat Apr 21, 2007 8:54 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by mduell »

jhsrennie wrote: Sat May 19, 2018 5:01 pmI shall draw a line here. I can provide logs if anyone wants them, but I doubt they will be helpful since the logs look the same whether or not Hyper-V is installed.
Except where the number of processors differs, as I pointed out above based on your logs?
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

@mduell these are the logs with and without Hyper-V installed. The only difference I can see is that without Hyper-V we get:

x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2

and with Hyper-V installed this line becomes:

x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX BMI2

Without Hyper-V:

Code: Select all

HandBrake 1.1.0 (2018040700) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
Ram: 4013 MB, 
GPU Information:
  Intel(R) HD Graphics 4400 - 10.18.14.4432
Screen: 1680x1050
Temp Dir: C:\Users\Administrator\AppData\Local\Temp\1\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake Team\HandBrake\1.1.0.0

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


# Starting Encode ...

[19:15:46] hb_init: starting libhb thread
[19:15:46] 1 job(s) to process
[19:15:46] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 6,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": true,
    "File": "D:\\Video\\Sausage Party-1.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "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": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "384",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 1,
    "Path": "D:\\Video\\Sausage Party.avi"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 21.0,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[19:15:46] CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
[19:15:46]  - Intel microarchitecture Haswell
[19:15:46]  - logical processor count: 4
[19:15:46] Intel Quick Sync Video support: yes
[19:15:46]  - Intel Media SDK hardware: API 1.17 (minimum: 1.3)
[19:15:46]  - H.264 encoder: yes
[19:15:46]     - preferred implementation: hardware (any) via D3D11
[19:15:46]     - capabilities (hardware):  breftype vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice
[19:15:46]  - H.265 encoder: no
[19:15:46] hb_scan: path=D:\Video\Sausage Party.avi, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image D:\Video\Sausage Party.avi
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
[19:15:46] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
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
[19:15:46] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'D:\Video\Sausage Party.avi':
  Duration: 01:28:45.24, start: 0.000000, bitrate: 2236 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) [XVID / 0x44495658]
      yuv420p, 720x384 [PAR 1:1 DAR 15:8]
      23.98 fps, 23.98 tbn
    Stream #0:1: Audio: ac3 [[0] [0][0] / 0x2000]
      48000 Hz, 5.1, fltp, 448 kb/s
[19:15:46] scan: decoding previews for title 1
[19:15:47] scan: audio 0x1: ac3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[19:15:47] scan: 10 previews, 720x384, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[19:15:47] libhb: scan thread found 1 valid title(s)
[19:15:47] starting job
[19:15:47] work: track 1, dithering not supported by codec
[19:15:47] work: only 1 chapter, disabling chapter markers
[19:15:47] job configuration:
[19:15:47]  * source
[19:15:47]    + D:\Video\Sausage Party.avi
[19:15:47]    + title 1, chapter(s) 1 to 1
[19:15:47]    + container: avi
[19:15:47]    + data rate: 2236 kbps
[19:15:47]  * destination
[19:15:47]    + D:\Video\Sausage Party-1.m4v
[19:15:47]    + container: MPEG-4 (libavformat)
[19:15:47]      + align initial A/V stream timestamps
[19:15:47]  * video track
[19:15:47]    + decoder: mpeg4
[19:15:47]    + filters
[19:15:47]      + 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)
[19:15:47]      + Decomb (mode=39)
[19:15:47]      + Framerate Shaper (mode=2:rate=27000000/900000)
[19:15:47]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[19:15:47]      + Crop and Scale (width=720:height=384:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[19:15:47]        + source: 720 * 384, crop (0/0/0/0): 720 * 384, scale: 720 * 384
[19:15:47]    + Output geometry
[19:15:47]      + storage dimensions: 720 x 384
[19:15:47]      + pixel aspect ratio: 1 : 1
[19:15:47]      + display dimensions: 720 x 384
[19:15:47]    + encoder: H.264 (libx264)
[19:15:47]      + preset:  fast
[19:15:47]      + profile: main
[19:15:47]      + level:   3.1
[19:15:47]      + quality: 21.00 (RF)
[19:15:47]  * audio track 1
[19:15:47]    + decoder: Unknown (AC3) (5.1 ch) (track 1, id 0x1)
[19:15:47]      + bitrate: 448 kbps, samplerate: 48000 Hz
[19:15:47]    + mixdown: Dolby Pro Logic II
[19:15:47]    + encoder: AAC (libavcodec)
[19:15:47]      + bitrate: 160 kbps, samplerate: 48000 Hz
[19:15:47] decomb filter thread started for segment 0
[19:15:47] decomb filter thread started for segment 1
[19:15:47] decomb filter thread started for segment 2
[19:15:47] decomb filter thread started for segment 3
[19:15:47] decomb check thread started for segment 0
[19:15:47] decomb check thread started for segment 1
[19:15:47] decomb check thread started for segment 2
[19:15:47] decomb check thread started for segment 3
[19:15:47] mask filter thread started for segment 0
[19:15:47] mask filter thread started for segment 1
[19:15:47] mask filter thread started for segment 2
[19:15:47] mask filter thread started for segment 3
[19:15:47] mask erode thread started for segment 0
[19:15:47] mask erode thread started for segment 1
[19:15:47] mask erode thread started for segment 2
[19:15:47] mask erode thread started for segment 3
[19:15:47] mask dilate thread started for segment 0
[19:15:47] mask dilate thread started for segment 1
[19:15:47] mask dilate thread started for segment 2
[19:15:47] mask dilate thread started for segment 3
[19:15:47] yadif thread started for segment 2
[19:15:47] yadif thread started for segment 3
[19:15:47] yadif thread started for segment 0
[19:15:47] yadif thread started for segment 1
[19:15:47] sync: expecting 127678 video frames
[19:15:47] encx264: min-keyint: 24, keyint: 240
[19:15:47] encx264: encoding at constant RF 21.000000
[19:15:47] encx264: unparsed options: level=3.1:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=14000:vbv-maxrate=14000:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x264 [info]: profile Main, level 3.1
[19:15:47] sync: first pts video is 0
[19:15:47] sync: "Chapter 1" (1) at frame 1 time 0
[19:15:47] sync: first pts audio 0x1 is 0
[19:35:01] reader: done. 1 scr changes
[19:35:01] work: average encoding speed for job is 110.572182 fps
[19:35:01] comb detect: heavy 1306 | light 5806 | uncombed 120565 | total 127677
[19:35:01] decomb: deinterlaced 1306 | blended 5806 | unfiltered 120565 | total 127677
[19:35:01] vfr: 127677 frames output, 0 dropped and 0 duped for CFR/PFR
[19:35:01] vfr: lost time: 0 (0 frames)
[19:35:01] vfr: gained time: 0 (0 frames) (0 not accounted for)
[19:35:01] ac3-decoder done: 166413 frames, 0 decoder errors
[19:35:01] mpeg4-decoder done: 127678 frames, 0 decoder errors
[19:35:01] sync: got 127677 frames, 127678 expected
[19:35:01] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:1862  Avg QP:17.28  size: 25527
x264 [info]: frame P:43940 Avg QP:20.55  size:  7724
x264 [info]: frame B:81875 Avg QP:23.40  size:  2222
x264 [info]: consecutive B-frames:  4.7% 21.5% 23.4% 50.3%
x264 [info]: mb I  I16..4: 35.3%  0.0% 64.7%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.9%  P16..4: 36.0% 16.5%  8.8%  0.0%  0.0%    skip:19.6%
x264 [info]: mb B  I16..4:  2.5%  0.0%  1.6%  B16..8: 27.8%  7.3%  0.7%  direct: 7.2%  skip:52.9%  L0:39.5% L1:45.6% BI:14.9%
x264 [info]: coded y,uvDC,uvAC intra: 49.5% 73.7% 33.7% inter: 11.6% 22.4% 2.1%
x264 [info]: i16 v,h,dc,p: 34% 27% 18% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 25% 24% 18%  5%  6%  6%  6%  5%  5%
x264 [info]: i8c dc,h,v,p: 44% 25% 23%  8%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.0%
x264 [info]: ref P L0: 75.9% 24.1%
x264 [info]: ref B L0: 84.8% 15.2%
x264 [info]: ref B L1: 97.1%  2.9%
x264 [info]: kb/s:854.55
[19:35:02] mux: track 0, 127677 frames, 568770547 bytes, 854.45 kbps, fifo 4096
[19:35:02] mux: track 1, 249620 frames, 106699474 bytes, 160.29 kbps, fifo 8192
[19:35:02] libhb: work result = 0

# Encode Completed ...
With Hyper-V:

Code: Select all

HandBrake 1.1.0 (2018040700) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
Ram: 4013 MB, 
GPU Information:
  Intel(R) HD Graphics 4400 - 10.18.14.4432
Screen: 1680x1050
Temp Dir: C:\Users\Administrator\AppData\Local\Temp\1\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake Team\HandBrake\1.1.0.0

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


# Starting Encode ...

[17:48:52] hb_init: starting libhb thread
[17:48:52] 1 job(s) to process
[17:48:52] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 6,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": true,
    "File": "D:\\Video\\Sausage Party-1.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "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": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "384",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 1,
    "Path": "D:\\Video\\Sausage Party.avi"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 21.0,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[17:48:52] CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
[17:48:52]  - Intel microarchitecture Haswell
[17:48:52]  - logical processor count: 4
[17:48:52] Intel Quick Sync Video support: yes
[17:48:52]  - Intel Media SDK hardware: API 1.17 (minimum: 1.3)
[17:48:52]  - H.264 encoder: yes
[17:48:52]     - preferred implementation: hardware (any) via D3D11
[17:48:52]     - capabilities (hardware):  breftype vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice
[17:48:52]  - H.265 encoder: no
[17:48:52] hb_scan: path=D:\Video\Sausage Party.avi, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image D:\Video\Sausage Party.avi
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
[17:48:52] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
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
[17:48:52] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'D:\Video\Sausage Party.avi':
  Duration: 01:28:45.24, start: 0.000000, bitrate: 2236 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) [XVID / 0x44495658]
      yuv420p, 720x384 [PAR 1:1 DAR 15:8]
      23.98 fps, 23.98 tbn
    Stream #0:1: Audio: ac3 [[0] [0][0] / 0x2000]
      48000 Hz, 5.1, fltp, 448 kb/s
[17:48:53] scan: decoding previews for title 1
[17:48:53] scan: audio 0x1: ac3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[17:48:53] scan: 10 previews, 720x384, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[17:48:53] libhb: scan thread found 1 valid title(s)
[17:48:53] starting job
[17:48:53] work: track 1, dithering not supported by codec
[17:48:53] work: only 1 chapter, disabling chapter markers
[17:48:53] job configuration:
[17:48:53]  * source
[17:48:53]    + D:\Video\Sausage Party.avi
[17:48:53]    + title 1, chapter(s) 1 to 1
[17:48:53]    + container: avi
[17:48:53]    + data rate: 2236 kbps
[17:48:53]  * destination
[17:48:53]    + D:\Video\Sausage Party-1.m4v
[17:48:53]    + container: MPEG-4 (libavformat)
[17:48:53]      + align initial A/V stream timestamps
[17:48:53]  * video track
[17:48:53]    + decoder: mpeg4
[17:48:53]    + filters
[17:48:53]      + 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)
[17:48:53]      + Decomb (mode=39)
[17:48:53]      + Framerate Shaper (mode=2:rate=27000000/900000)
[17:48:53]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[17:48:53]      + Crop and Scale (width=720:height=384:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[17:48:53]        + source: 720 * 384, crop (0/0/0/0): 720 * 384, scale: 720 * 384
[17:48:53]    + Output geometry
[17:48:53]      + storage dimensions: 720 x 384
[17:48:53]      + pixel aspect ratio: 1 : 1
[17:48:53]      + display dimensions: 720 x 384
[17:48:53]    + encoder: H.264 (libx264)
[17:48:53]      + preset:  fast
[17:48:53]      + profile: main
[17:48:53]      + level:   3.1
[17:48:53]      + quality: 21.00 (RF)
[17:48:53]  * audio track 1
[17:48:53]    + decoder: Unknown (AC3) (5.1 ch) (track 1, id 0x1)
[17:48:53]      + bitrate: 448 kbps, samplerate: 48000 Hz
[17:48:53]    + mixdown: Dolby Pro Logic II
[17:48:53]    + encoder: AAC (libavcodec)
[17:48:53]      + bitrate: 160 kbps, samplerate: 48000 Hz
[17:48:53] decomb filter thread started for segment 0
[17:48:53] decomb filter thread started for segment 1
[17:48:53] decomb filter thread started for segment 3
[17:48:53] decomb filter thread started for segment 2
[17:48:53] decomb check thread started for segment 1
[17:48:53] mask erode thread started for segment 3
[17:48:53] decomb check thread started for segment 3
[17:48:53] decomb check thread started for segment 2
[17:48:53] mask filter thread started for segment 0
[17:48:53] mask filter thread started for segment 1
[17:48:53] mask filter thread started for segment 2
[17:48:53] mask filter thread started for segment 3
[17:48:53] mask erode thread started for segment 0
[17:48:53] mask erode thread started for segment 2
[17:48:53] decomb check thread started for segment 0
[17:48:53] mask erode thread started for segment 1
[17:48:53] mask dilate thread started for segment 0
[17:48:53] mask dilate thread started for segment 1
[17:48:53] mask dilate thread started for segment 2
[17:48:53] mask dilate thread started for segment 3
[17:48:53] yadif thread started for segment 1
[17:48:53] yadif thread started for segment 0
[17:48:53] yadif thread started for segment 2
[17:48:53] yadif thread started for segment 3
[17:48:54] sync: expecting 127678 video frames
[17:48:54] encx264: min-keyint: 24, keyint: 240
[17:48:54] encx264: encoding at constant RF 21.000000
[17:48:54] encx264: unparsed options: level=3.1:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=14000:vbv-maxrate=14000:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX BMI2
x264 [info]: profile Main, level 3.1
[17:48:54] sync: first pts video is 0
[17:48:54] sync: "Chapter 1" (1) at frame 1 time 0
[17:48:54] sync: first pts audio 0x1 is 0
[18:23:36] reader: done. 1 scr changes
[18:23:37] work: average encoding speed for job is 61.302006 fps
[18:23:37] comb detect: heavy 1306 | light 5806 | uncombed 120565 | total 127677
[18:23:37] decomb: deinterlaced 1306 | blended 5806 | unfiltered 120565 | total 127677
[18:23:37] vfr: 127677 frames output, 0 dropped and 0 duped for CFR/PFR
[18:23:37] vfr: lost time: 0 (0 frames)
[18:23:37] vfr: gained time: 0 (0 frames) (0 not accounted for)
[18:23:37] ac3-decoder done: 166413 frames, 0 decoder errors
[18:23:37] mpeg4-decoder done: 127678 frames, 0 decoder errors
[18:23:37] sync: got 127677 frames, 127678 expected
[18:23:37] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:1862  Avg QP:17.28  size: 25527
x264 [info]: frame P:43940 Avg QP:20.55  size:  7724
x264 [info]: frame B:81875 Avg QP:23.40  size:  2222
x264 [info]: consecutive B-frames:  4.7% 21.5% 23.4% 50.3%
x264 [info]: mb I  I16..4: 35.3%  0.0% 64.7%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.9%  P16..4: 36.0% 16.5%  8.8%  0.0%  0.0%    skip:19.6%
x264 [info]: mb B  I16..4:  2.5%  0.0%  1.6%  B16..8: 27.8%  7.3%  0.7%  direct: 7.2%  skip:52.9%  L0:39.5% L1:45.6% BI:14.9%
x264 [info]: coded y,uvDC,uvAC intra: 49.5% 73.7% 33.7% inter: 11.6% 22.4% 2.1%
x264 [info]: i16 v,h,dc,p: 34% 27% 18% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 25% 24% 18%  5%  6%  6%  6%  5%  5%
x264 [info]: i8c dc,h,v,p: 44% 25% 23%  8%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.0%
x264 [info]: ref P L0: 75.9% 24.1%
x264 [info]: ref B L0: 84.8% 15.2%
x264 [info]: ref B L1: 97.1%  2.9%
x264 [info]: kb/s:854.55
[18:23:37] mux: track 0, 127677 frames, 568770547 bytes, 854.45 kbps, fifo 4096
[18:23:37] mux: track 1, 249620 frames, 106699474 bytes, 160.29 kbps, fifo 8192
[18:23:37] libhb: work result = 0

# Encode Completed ...
User avatar
BradleyS
Moderator
Posts: 1860
Joined: Thu Aug 09, 2007 12:16 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by BradleyS »

AVX2 instructions are twice as wide as AVX instructions, so theoretically where there are such optimizations you should get twice the throughput.
jhsrennie
Posts: 10
Joined: Tue Sep 07, 2010 4:21 pm

Re: Handbrake is unexpectedly slow on Server 2008 R2

Post by jhsrennie »

And just for completeness here is the log for a Server 2008 R2 VM running on Server 2008 R2 with Hyper-V.

The curious thing is that installing Hyper-V halves the frame rate, but a VM running on that Hyper-V server manages close to the original frame rate. In this particular case the frame rates were:

Code: Select all

OS             fps
Windows 7      113
Server 2k8     113
2k8 + Hyper-V   61
2k8 in a VM    106
I can't see anything in the logs to suggest why such a drastic difference exists. The only difference I can see is in the CPU capabilities:

W7 - x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
2k8 - x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
HV - x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX BMI2
VM - x264 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX

Code: Select all

HandBrake 1.1.0 (2018040700) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
Ram: 3071 MB, 
GPU Information:
  Microsoft Virtual Machine Bus Video Device - 6.1.7601.17514
Screen: 1024x768
Temp Dir: C:\Users\Administrator\AppData\Local\Temp\1\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\Administrator\AppData\Roaming\HandBrake Team\HandBrake\1.1.0.0

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


# Starting Encode ...

[16:15:17] hb_init: starting libhb thread
[16:15:17] 1 job(s) to process
[16:15:17] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 6,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": true,
    "File": "C:\\Video\\Sausage Party-1.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "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": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "384",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 1,
    "Path": "C:\\Video\\Sausage Party.avi"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": false,
      "Forced": false
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "3.1",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 21.0,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[16:15:17] CPU: Intel(R) Core(TM) i3-4150T CPU @ 3.00GHz
[16:15:17]  - Intel microarchitecture Haswell
[16:15:17]  - logical processor count: 4
[16:15:17] Intel Quick Sync Video support: no
[16:15:17] hb_scan: path=C:\Video\Sausage Party.avi, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image C:\Video\Sausage Party.avi
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
[16:15:17] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.3
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
[16:15:17] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'C:\Video\Sausage Party.avi':
  Duration: 01:28:45.24, start: 0.000000, bitrate: 2236 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) [XVID / 0x44495658]
      yuv420p, 720x384 [PAR 1:1 DAR 15:8]
      23.98 fps, 23.98 tbn
    Stream #0:1: Audio: ac3 [[0] [0][0] / 0x2000]
      48000 Hz, 5.1, fltp, 448 kb/s
[16:15:17] scan: decoding previews for title 1
[16:15:18] scan: audio 0x1: ac3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[16:15:18] scan: 10 previews, 720x384, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[16:15:18] libhb: scan thread found 1 valid title(s)
[16:15:18] starting job
[16:15:18] work: track 1, dithering not supported by codec
[16:15:18] work: only 1 chapter, disabling chapter markers
[16:15:18] job configuration:
[16:15:18]  * source
[16:15:18]    + C:\Video\Sausage Party.avi
[16:15:18]    + title 1, chapter(s) 1 to 1
[16:15:18]    + container: avi
[16:15:18]    + data rate: 2236 kbps
[16:15:18]  * destination
[16:15:18]    + C:\Video\Sausage Party-1.m4v
[16:15:18]    + container: MPEG-4 (libavformat)
[16:15:18]      + align initial A/V stream timestamps
[16:15:18]  * video track
[16:15:18]    + decoder: mpeg4
[16:15:18]    + filters
[16:15:18]      + 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)
[16:15:18]      + Decomb (mode=39)
[16:15:18]      + Framerate Shaper (mode=2:rate=27000000/900000)
[16:15:18]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[16:15:18]      + Crop and Scale (width=720:height=384:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[16:15:18]        + source: 720 * 384, crop (0/0/0/0): 720 * 384, scale: 720 * 384
[16:15:18]    + Output geometry
[16:15:18]      + storage dimensions: 720 x 384
[16:15:18]      + pixel aspect ratio: 1 : 1
[16:15:18]      + display dimensions: 720 x 384
[16:15:18]    + encoder: H.264 (libx264)
[16:15:18]      + preset:  fast
[16:15:18]      + profile: main
[16:15:18]      + level:   3.1
[16:15:18]      + quality: 21.00 (RF)
[16:15:18]  * audio track 1
[16:15:18]    + decoder: Unknown (AC3) (5.1 ch) (track 1, id 0x1)
[16:15:18]      + bitrate: 448 kbps, samplerate: 48000 Hz
[16:15:18]    + mixdown: Dolby Pro Logic II
[16:15:18]    + encoder: AAC (libavcodec)
[16:15:18]      + bitrate: 160 kbps, samplerate: 48000 Hz
[16:15:18] decomb filter thread started for segment 0
[16:15:18] decomb filter thread started for segment 1
[16:15:18] decomb filter thread started for segment 2
[16:15:18] decomb filter thread started for segment 3
[16:15:18] decomb check thread started for segment 0
[16:15:18] decomb check thread started for segment 1
[16:15:18] decomb check thread started for segment 2
[16:15:18] decomb check thread started for segment 3
[16:15:18] mask filter thread started for segment 0
[16:15:18] mask filter thread started for segment 1
[16:15:18] mask filter thread started for segment 2
[16:15:18] mask filter thread started for segment 3
[16:15:18] mask erode thread started for segment 0
[16:15:18] mask erode thread started for segment 1
[16:15:18] mask erode thread started for segment 2
[16:15:18] mask erode thread started for segment 3
[16:15:18] mask dilate thread started for segment 0
[16:15:18] mask dilate thread started for segment 1
[16:15:18] mask dilate thread started for segment 2
[16:15:18] mask dilate thread started for segment 3
[16:15:18] yadif thread started for segment 0
[16:15:18] yadif thread started for segment 1
[16:15:18] yadif thread started for segment 2
[16:15:18] yadif thread started for segment 3
[16:15:18] sync: expecting 127678 video frames
[16:15:18] encx264: min-keyint: 24, keyint: 240
[16:15:18] encx264: encoding at constant RF 21.000000
[16:15:18] encx264: unparsed options: level=3.1:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=14000:vbv-maxrate=14000:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX
x264 [info]: profile Main, level 3.1
[16:15:18] sync: first pts video is 0
[16:15:18] sync: "Chapter 1" (1) at frame 1 time 0
[16:15:18] sync: first pts audio 0x1 is 0
[16:35:20] reader: done. 1 scr changes
[16:35:20] work: average encoding speed for job is 106.193703 fps
[16:35:20] comb detect: heavy 1306 | light 5806 | uncombed 120565 | total 127677
[16:35:20] decomb: deinterlaced 1306 | blended 5806 | unfiltered 120565 | total 127677
[16:35:20] vfr: 127677 frames output, 0 dropped and 0 duped for CFR/PFR
[16:35:20] vfr: lost time: 0 (0 frames)
[16:35:20] vfr: gained time: 0 (0 frames) (0 not accounted for)
[16:35:20] ac3-decoder done: 166413 frames, 0 decoder errors
[16:35:20] mpeg4-decoder done: 127678 frames, 0 decoder errors
[16:35:20] sync: got 127677 frames, 127678 expected
[16:35:20] sync: framerate min 23.981 fps, max 23.981 fps, avg 23.976 fps
x264 [info]: frame I:1862  Avg QP:17.28  size: 25527
x264 [info]: frame P:43940 Avg QP:20.55  size:  7724
x264 [info]: frame B:81875 Avg QP:23.40  size:  2222
x264 [info]: consecutive B-frames:  4.7% 21.5% 23.4% 50.3%
x264 [info]: mb I  I16..4: 35.3%  0.0% 64.7%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.9%  P16..4: 36.0% 16.5%  8.8%  0.0%  0.0%    skip:19.6%
x264 [info]: mb B  I16..4:  2.5%  0.0%  1.6%  B16..8: 27.8%  7.3%  0.7%  direct: 7.2%  skip:52.9%  L0:39.5% L1:45.6% BI:14.9%
x264 [info]: coded y,uvDC,uvAC intra: 49.5% 73.7% 33.7% inter: 11.6% 22.4% 2.1%
x264 [info]: i16 v,h,dc,p: 34% 27% 18% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 25% 24% 18%  5%  6%  6%  6%  5%  5%
x264 [info]: i8c dc,h,v,p: 44% 25% 23%  8%
x264 [info]: Weighted P-Frames: Y:3.5% UV:2.0%
x264 [info]: ref P L0: 75.9% 24.1%
x264 [info]: ref B L0: 84.8% 15.2%
x264 [info]: ref B L1: 97.1%  2.9%
x264 [info]: kb/s:854.55
[16:35:21] mux: track 0, 127677 frames, 568769264 bytes, 854.45 kbps, fifo 4096
[16:35:21] mux: track 1, 249620 frames, 106699474 bytes, 160.29 kbps, fifo 8192
[16:35:21] libhb: work result = 0

# Encode Completed ...
Post Reply