Handbrake elapsed time and time remaining not accurate

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
OstinatoFreak
Posts: 42
Joined: Tue Dec 13, 2016 2:38 pm

Handbrake elapsed time and time remaining not accurate

Post by OstinatoFreak »

Handbrake used to nail elapsed time and estimated time to completion. Now it is always really far off during the entire encode. See https://imgur.com/a/edOIjKS

Please forgive the bottom screenshot - imgur clipped it, but you will still get the idea. Even looking at the first screenshot alone, if you look at the circled items, you'll see the numbers just don't make any sense. 24 hours later (the bottom screenshot), it makes even less sense.

Running Windows 10, Handbrake 1.3.1.

Picture Settings: Anamorphic: Automatic
Crop Top: 42, Bottom: 42, Left: 0, Right: 0
Video: H.265 (x265) - 22RF
Audio: 1 English - Surround 7.1 to Auto Passthru
Subtitles: Foreign Audio Scan
1 English [PGS]
Advanced: Preset: veryfast
Tune: None
Profile: main
Level: auto
Extra Arguments: strong-intra-smoothing=0:rect=0

Code: Select all

HandBrake 1.3.1 (2020010400)
OS: Microsoft Windows NT 10.0.18363.0
CPU: Intel(R) Core(TM) i5-6500 CPU @ 3.20GHz
Ram: 16320 MB, 
GPU Information:
  NVIDIA GeForce RTX 2070 - 26.21.14.4219
Screen: 3840x2160
Temp Dir: C:\Users\user\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake
Data Dir: C:\Users\user\AppData\Roaming\HandBrake

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


# Starting Encode ...

[11:32:42] base preset: 1080p h265 (Modified)
[23:32:42] hb_init: starting libhb thread
[23:32:42] Starting work at: Tue Mar 03 23:32:42 2020
[23:32:42] 1 job(s) to process
[23:32:42] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0.0,
        "Encoder": "copy",
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Name": "Surround 7.1",
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      "copy:aac",
      "copy:ac3",
      "copy:dtshd",
      "copy:dts",
      "copy:eac3",
      "copy:flac",
      "copy:mp3",
      "copy:truehd"
    ],
    "FallbackEncoder": "ac3"
  },
  "Destination": {
    "ChapterList": [],
    "ChapterMarkers": false,
    "AlignAVStart": false,
    "File": "A:\\Movies\\movie.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "mkv"
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 4,
        "Settings": {
          "mode": "7"
        }
      },
      {
        "ID": 3,
        "Settings": {
          "block-height": "16",
          "block-thresh": "40",
          "block-width": "16",
          "filter-mode": "2",
          "mode": "3",
          "motion-thresh": "1",
          "spatial-metric": "2",
          "spatial-thresh": "1"
        }
      },
      {
        "ID": 9,
        "Settings": {
          "cb-frame-count": 2,
          "cb-origin-tune": 0.6,
          "cb-patch-size": 7,
          "cb-prefilter": 0,
          "cb-range": 3,
          "cb-strength": 10.0,
          "y-frame-count": 2,
          "y-origin-tune": 0.6,
          "y-patch-size": 7,
          "y-prefilter": 0,
          "y-range": 3,
          "y-strength": 8.0
        }
      },
      {
        "ID": 12,
        "Settings": {
          "crop-bottom": "42",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "42",
          "height": "2076",
          "width": "3840"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 16
    },
    "Title": 1,
    "Path": "A:\\Movies\\movie.mkv"
  },
  "Subtitle": {
    "Search": {
      "Burn": true,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": [
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 1,
        "Offset": 0,
        "Track": 0
      }
    ]
  },
  "Video": {
    "Encoder": "x265",
    "Level": "auto",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "strong-intra-smoothing=0:rect=0",
    "Preset": "veryfast",
    "Profile": "main",
    "Quality": 22.0,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[23:32:42] CPU: Intel(R) Core(TM) i5-6500 CPU @ 3.20GHz
[23:32:42]  - Intel microarchitecture Skylake
[23:32:42]  - logical processor count: 4
[23:32:42] Intel Quick Sync Video support: no
[23:32:42] hb_scan: path=A:\Movies\movie.mkv, title_index=1
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image A:\Movies\movie.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(A:\Movies\movie.mkv\) failed
[23:32:42] 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
[23:32:42] dvd: not a dvd - trying as a stream/file instead
Input #0, matroska,webm, from 'A:\Movies\movie.mkv':
  Metadata:
    title           : movie
    encoder         : libmakemkv v1.14.7 (1.3.5/1.4.7) win(x64-release)
    creation_time   : 2020-01-19T00:58:52.000000Z
  Duration: 02:15:09.56, start: 0.000000, bitrate: 54772 kb/s
    Chapter #0:0: start 0.000000, end 388.846792
    Metadata:
      title           : Chapter 01
    Chapter #0:1: start 388.846792, end 595.886958
    Metadata:
      title           : Chapter 02
    Chapter #0:2: start 595.886958, end 1533.698833
    Metadata:
      title           : Chapter 03
    Chapter #0:3: start 1533.698833, end 3200.572375
    Metadata:
      title           : Chapter 04
    Chapter #0:4: start 3200.572375, end 3576.447875
    Metadata:
      title           : Chapter 05
    Chapter #0:5: start 3576.447875, end 4504.124625
    Metadata:
      title           : Chapter 06
    Chapter #0:6: start 4504.124625, end 4882.460917
    Metadata:
      title           : Chapter 07
    Chapter #0:7: start 4882.460917, end 5118.071292
    Metadata:
      title           : Chapter 08
    Chapter #0:8: start 5118.071292, end 5970.089125
    Metadata:
      title           : Chapter 09
    Chapter #0:9: start 5970.089125, end 6228.430542
    Metadata:
      title           : Chapter 10
    Chapter #0:10: start 6228.430542, end 6547.874667
    Metadata:
      title           : Chapter 11
    Chapter #0:11: start 6547.874667, end 6918.369792
    Metadata:
      title           : Chapter 12
    Chapter #0:12: start 6918.369792, end 7025.852167
    Metadata:
      title           : Chapter 13
    Chapter #0:13: start 7025.852167, end 7179.964458
    Metadata:
      title           : Chapter 14
    Chapter #0:14: start 7179.964458, end 7591.000083
    Metadata:
      title           : Chapter 15
    Chapter #0:15: start 7591.000083, end 8109.559792
    Metadata:
      title           : Chapter 16
    Stream #0:0(eng): Video: hevc (Main 10), yuv420p10le(tv, bt2020nc/bt2020/smpte2084), 3840x2160 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 23.98 tbc
    Metadata:
      BPS-eng         : 44955696
      DURATION-eng    : 02:15:09.559791666
      NUMBER_OF_FRAMES-eng: 194435
      NUMBER_OF_BYTES-eng: 45571358846
      SOURCE_ID-eng   : 001011
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.14.7 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2020-01-19 00:58:52
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
    Stream #0:1(eng): Audio: dts (DTS-HD MA), 48000 Hz, 7.1, s32p (24 bit) (default)
    Metadata:
      title           : Surround 7.1
      BPS-eng         : 8280790
      DURATION-eng    : 02:15:09.578666666
      NUMBER_OF_FRAMES-eng: 760273
      NUMBER_OF_BYTES-eng: 8394214404
      SOURCE_ID-eng   : 001100
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.14.7 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2020-01-19 00:58:52
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
    Stream #0:2(eng): Audio: dts (DTS), 48000 Hz, 5.1(side), fltp, 1536 kb/s
    Metadata:
      title           : Surround 5.1
      BPS-eng         : 1509000
      DURATION-eng    : 02:15:09.578666666
      NUMBER_OF_FRAMES-eng: 760273
      NUMBER_OF_BYTES-eng: 1529669276
      SOURCE_ID-eng   : 001100
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.14.7 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2020-01-19 00:58:52
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
    Stream #0:3(eng): Subtitle: hdmv_pgs_subtitle (default)
    Metadata:
      BPS-eng         : 24685
      DURATION-eng    : 02:03:52.612687500
      NUMBER_OF_FRAMES-eng: 2322
      NUMBER_OF_BYTES-eng: 22935046
      SOURCE_ID-eng   : 0012A0
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.14.7 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2020-01-19 00:58:52
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
[23:32:42] scan: decoding previews for title 1
[23:32:42] scan: audio 0x1: dca, rate=48000Hz, bitrate=1 English (DTS-HD MA) (7.1 ch)
[23:32:42] scan: audio 0x2: dca, rate=48000Hz, bitrate=1536000 English (DTS) (5.1 ch) (1536 kbps)
[23:32:47] scan: 10 previews, 3840x2160, 23.976 fps, autocrop = 42/42/0/0, aspect 16:9, PAR 1:1
[23:32:47] scan: supported video decoders: avcodec qsv
[23:32:47] libhb: scan thread found 1 valid title(s)
[23:32:47] Starting Task: Subtitle Scan
[23:32:47] NLMeans using SSE2 optimizations
[23:32:47] NLMeans using 4 threads
[23:32:47] job configuration:
[23:32:47]  * source
[23:32:47]    + A:\Movies\movie.mkv
[23:32:47]    + title 1, chapter(s) 1 to 16
[23:32:47]    + container: matroska,webm
[23:32:47]    + data rate: 54772 kbps
[23:32:47]  * destination
[23:32:47]    + A:\Movies\movie.mkv
[23:32:47]    + container: Matroska (libavformat)
[23:32:47]  * video track
[23:32:47]    + decoder: hevc
[23:32:47]    + filters
[23:32: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)
[23:32:47]      + Decomb (mode=39)
[23:32:47]      + Framerate Shaper (mode=2)
[23:32:47]        + frame rate: 23.976 fps -> peak rate limited to 23.976 fps
[23:32:47]      + Denoise (nlmeans) (y-strength=8:y-origin-tune=0.6:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=10:cb-origin-tune=0.6:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[23:32:47]      + Crop and Scale (width=3840:height=2076:crop-top=42:crop-bottom=42:crop-left=0:crop-right=0)
[23:32:47]        + source: 3840 * 2160, crop (42/42/0/0): 3840 * 2076, scale: 3840 * 2076
[23:32:47]    + Output geometry
[23:32:47]      + storage dimensions: 3840 x 2076
[23:32:47]      + pixel aspect ratio: 1 : 1
[23:32:47]      + display dimensions: 3840 x 2076
[23:32:47]  * Foreign Audio Search: Render/Burn-in, Forced Only
[23:32:47]    + subtitle, English [PGS] (track 0, id 0x3, Picture)
[23:32:47] sync: expecting 194434 video frames
[23:37:54] reader: done. 1 scr changes
[23:37:54] work: average encoding speed for job is 0.000000 fps
[23:37:54] comb detect: heavy 0 | light 0 | uncombed 0 | total 0
[23:37:54] decomb: deinterlaced 0 | blended 0 | unfiltered 0 | total 0
[23:37:54] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[23:37:54] vfr: lost time: 0 (0 frames)
[23:37:54] vfr: gained time: 0 (0 frames) (0 not accounted for)
[23:37:54] hevc-decoder done: 0 frames, 0 decoder errors
[23:37:54] sync: got 0 frames, 194434 expected
[23:37:54] Subtitle track 0 (id 0x3) 'English [PGS]': 1161 hits (0 forced)
[23:37:54] No candidate detected during subtitle scan
[23:37:54] Starting Task: Encoding Pass
[23:37:54] NLMeans using SSE2 optimizations
[23:37:54] NLMeans using 4 threads
[23:37:54] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[23:37:54] Auto Passthru: fallback is AC3
[23:37:54] Auto Passthru: using DTS-HD Passthru for track 1
[23:37:54] job configuration:
[23:37:54]  * source
[23:37:54]    + A:\Movies\movie.mkv
[23:37:54]    + title 1, chapter(s) 1 to 16
[23:37:54]    + container: matroska,webm
[23:37:54]    + data rate: 54772 kbps
[23:37:54]  * destination
[23:37:54]    + A:\Movies\movie.mkv
[23:37:54]    + container: Matroska (libavformat)
[23:37:54]  * video track
[23:37:54]    + decoder: hevc
[23:37:54]    + filters
[23:37:54]      + 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)
[23:37:54]      + Decomb (mode=39)
[23:37:54]      + Framerate Shaper (mode=2)
[23:37:54]        + frame rate: 23.976 fps -> peak rate limited to 23.976 fps
[23:37:54]      + Denoise (nlmeans) (y-strength=8:y-origin-tune=0.6:y-patch-size=7:y-range=3:y-frame-count=2:y-prefilter=0:cb-strength=10:cb-origin-tune=0.6:cb-patch-size=7:cb-range=3:cb-frame-count=2:cb-prefilter=0)
[23:37:54]      + Crop and Scale (width=3840:height=2076:crop-top=42:crop-bottom=42:crop-left=0:crop-right=0)
[23:37:54]        + source: 3840 * 2160, crop (42/42/0/0): 3840 * 2076, scale: 3840 * 2076
[23:37:54]    + Output geometry
[23:37:54]      + storage dimensions: 3840 x 2076
[23:37:54]      + pixel aspect ratio: 1 : 1
[23:37:54]      + display dimensions: 3840 x 2076
[23:37:54]    + encoder: H.265 (libx265)
[23:37:54]      + preset:  veryfast
[23:37:54]      + options: strong-intra-smoothing=0:rect=0
[23:37:54]      + profile: main
[23:37:54]      + level:   auto
[23:37:54]      + quality: 22.00 (RF)
[23:37:54]      + color profile: 9-16-9
[23:37:54]  * subtitle track 1, English [PGS] (track 0, id 0x3, Picture) -> Passthrough
[23:37:54]  * audio track 1
[23:37:54]    + name: Surround 7.1
[23:37:54]    + decoder: English (DTS-HD MA) (7.1 ch) (track 1, id 0x1)
[23:37:54]      + samplerate: 48000 Hz
[23:37:54]    + DTS-HD Passthru
[23:37:54] sync: expecting 194434 video frames
x265 [info]: HEVC encoder version 3.2.1+1-b5c86a64bbbe
x265 [info]: build info [Windows][GCC 9.2.0][64 bit] 8bit+10bit+12bit
x265 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x265 [info]: Main profile, Level-5 (Main tier)
x265 [info]: Thread pool created using 4 threads
x265 [info]: Slices                              : 1
x265 [info]: frame threads / pool features       : 2 / wpp(33 rows)
x265 [info]: Coding QT: max CU size, min CU size : 64 / 8
x265 [info]: Residual QT: max TU size, max depth : 32 / 1 inter / 1 intra
x265 [info]: ME / range / subpel / merge         : hex / 57 / 1 / 2
x265 [info]: Keyframe min / max / scenecut / bias: 24 / 240 / 40 / 5.00
x265 [info]: Lookahead / bframes / badapt        : 15 / 4 / 0
x265 [info]: b-pyramid / weightp / weightb       : 1 / 1 / 0
x265 [info]: References / ref-limit  cu / depth  : 2 / on / on
x265 [info]: AQ: mode / str / qg-size / cu-tree  : 2 / 1.0 / 32 / 1
x265 [info]: Rate Control / qCompress            : CRF-22.0 / 0.60
x265 [info]: tools: rd=2 psy-rd=2.00 early-skip rskip signhide tmvp fast-intra
x265 [info]: tools: lslices=8 deblock sao
[23:37:55] sync: first pts video is 0
[23:37:55] sync: "Chapter 1" (1) at frame 1 time 0
[23:37:55] sync: first pts audio 0x1 is 0
[23:49:28] sync: first pts subtitle 0x3 is 3926430
[01:32:50] sync: "Chapter 2" (2) at frame 9324 time 34996211
[02:33:25] sync: "Chapter 3" (3) at frame 14288 time 53629826
[07:10:44] sync: "Chapter 4" (4) at frame 36773 time 138032895
[15:40:17] sync: "Chapter 5" (5) at frame 76743 time 288070282
[17:41:20] sync: "Chapter 6" (6) at frame 85750 time 321880308
[22:15:12] sync: "Chapter 7" (7) at frame 107992 time 405371216
[00:04:43] sync: "Chapter 8" (8) at frame 117063 time 439421482
[01:19:08] sync: "Chapter 9" (9) at frame 122716 time 460641431
[05:42:10] sync: "Chapter 10" (10) at frame 143145 time 537326790
[06:55:20] sync: "Chapter 11" (11) at frame 149334 time 560558748
[08:28:40] sync: "Chapter 12" (12) at frame 156993 time 589308720
[10:22:45] sync: "Chapter 13" (13) at frame 165876 time 622653281
[10:55:06] sync: "Chapter 14" (14) at frame 168458 time 632345463
[11:40:07] sync: "Chapter 15" (15) at frame 172153 time 646215570
[13:44:07] sync: "Chapter 16" (16) at frame 182008 time 683208776
[15:59:48] reader: done. 1 scr changes
[16:00:54] work: average encoding speed for job is 1.337614 fps
[16:00:54] comb detect: heavy 1845 | light 4624 | uncombed 187966 | total 194435
[16:00:54] decomb: deinterlaced 1845 | blended 4624 | unfiltered 187966 | total 194435
[16:00:54] vfr: 194435 frames output, 0 dropped and 0 duped for CFR/PFR
[16:00:54] vfr: lost time: 0 (0 frames)
[16:00:54] vfr: gained time: 0 (0 frames) (0 not accounted for)
[16:00:54] dca-decoder done: 760273 frames, 0 decoder errors
[16:00:54] hevc-decoder done: 194435 frames, 0 decoder errors
[16:00:54] sync: got 194435 frames, 194434 expected
[16:00:54] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
x265 [info]: frame I:   1952, Avg QP:21.99  kb/s: 24664.44
x265 [info]: frame P:  39037, Avg QP:23.19  kb/s: 15116.53
x265 [info]: frame B: 153446, Avg QP:26.22  kb/s: 6311.94
x265 [info]: Weighted P-Frames: Y:1.3% UV:0.6%
x265 [info]: consecutive B-frames: 5.1% 0.8% 0.9% 1.0% 92.2%
encoded 194435 frames in 145380.23s (1.34 fps), 8263.90 kb/s, Avg QP:25.57
[16:00:55] mux: track 0, 194435 frames, 8377850870 bytes, 8264.58 kbps, fifo 512
[16:00:55] mux: track 1, 760273 frames, 8394214404 bytes, 8280.72 kbps, fifo 2048
[16:00:55] mux: track 2, 2322 frames, 22935046 bytes, 22.62 kbps, fifo 16
[16:00:55] Finished work at: Thu Mar 05 16:00:55 2020
[16:00:55] libhb: work result = 0

# Encode Completed ...
Woodstock
Veteran User
Posts: 4623
Joined: Tue Aug 27, 2013 6:39 am

Re: Handbrake elapsed time and time remaining not accurate

Post by Woodstock »

Post Reply