DVD ISO to MKV = different runtimes. (29.970628 versus 29.970029)

[Demat]

Hi all,

I have ripped several of my DVDs as ISOs and open them in Handbrake so that I can export as h264 mkv files.

All is working fine (various soundtracks, subtitles, quality settings are good, etc) - however, I wanted to compare the quality of the mkv versus the original ISO. So I opened both versions in VLC media player to screengrab identical frames from each, and that's when I noticed that my mkv is 3 seconds longer than the original movie.

If I hit play on both at the same time a delay slowly builds between them.
Oddly, if I watch the MKV version, the sound does not go out of sync at all - despite it being a passthru of the original. (As it's not re-encoded, I would have expected the sound to be out of sync by 3 seconds by the end of the movie - but it seems perfectly fine).

I googled what the common true value of 29.97fps is and it seems to be the same as the framerate of my ISOs (29.970029).
My Handbrake framerate is set to '29.97', constant, but this actually outputs 29.970628 fps.

I read somewhere that this difference, although small, will add up over an extended runtime - which I'm guessing is why my movie runs 3 seconds longer. It's not enough to notice when you're watching, but as a stickler for precision (and trying to avoid sync issues with passthru soundtracks..) can anyone please explain what's going on and whether there's a way to fix it? (Is there a way to force handbrake to use a custom framerate so I can match the source properly?)

(BTW: I did originally have the framerate set to 'same as source' but for some reason it output a 23.97fps file, even though the source ISO is definitely 29.970029)

cheers
Chris

HandBrake version (e.g., 1.0.7 64bit):

Windows 7 64bit :

HandBrake Activity Log)

Code: Select all

[17:07:37] sync: expecting 143428 video frames
[17:07:42] reader: done. 3 scr changes
[17:07:42] work: average encoding speed for job is 0.000000 fps
[17:07:42] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[17:07:42] vfr: lost time: 0 (0 frames)
[17:07:42] vfr: gained time: 0 (0 frames) (0 not accounted for)
[17:07:43] sync: got 0 frames, 143428 expected
[17:07:43] Subtitle track 0 (id 0x21bd) 'English': 1463 hits (0 forced)
[17:07:43] No candidate detected during subtitle scan
[17:07:43] starting job
[17:07:43] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[17:07:43] Auto Passthru: fallback is AC3
[17:07:43] Auto Passthru: using DTS Passthru for track 1
[17:07:43] Auto Passthru: using AC3 Passthru for track 2
[17:07:43] Auto Passthru: using AC3 Passthru for track 3
[17:07:43] Auto Passthru: using AC3 Passthru for track 4
[17:07:43] job configuration:
[17:07:43]  * source
[17:07:43]    + E:\DVD ISO BACKUPS\movie.ISO
[17:07:43]    + title 1, chapter(s) 1 to 45
[17:07:43]  * destination
[17:07:43]    + E:\DVD ISO BACKUPS\Movie.mkv
[17:07:43]    + container: Matroska (libavformat)
[17:07:43]      + chapter markers
[17:07:43]  * video track
[17:07:43]    + decoder: mpeg2video
[17:07:43]      + bitrate 9800 kbps
[17:07:43]    + filters
[17:07:43]      + Detelecine (pullup) ()
[17:07:43]      + Framerate Shaper (mode=1:rate=27000000/900900)
[17:07:43]        + frame rate: 23.976 fps -> constant 29.970 fps
[17:07:43]      + Crop and Scale (width=720:height=480:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[17:07:43]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[17:07:43]    + Output geometry
[17:07:43]      + storage dimensions: 720 x 480
[17:07:43]      + pixel aspect ratio: 32 : 27
[17:07:43]      + display dimensions: 853 x 480
[17:07:43]    + encoder: H.264 (libx264)
[17:07:43]      + preset:  slower
[17:07:43]      + tune:    film
[17:07:43]      + profile: high
[17:07:43]      + level:   4.0
[17:07:43]      + quality: 10.00 (RF)
[17:07:43]  * subtitle track 1, English (track 0, id 0x21bd) Picture [VOBSUB] -> Passthrough
[17:07:43]  * audio track 1
[17:07:43]    + decoder: English (DTS) (5.1 ch) (track 1, id 0x88bd)
[17:07:43]      + bitrate: 768 kbps, samplerate: 48000 Hz
[17:07:43]    + DTS Passthru
[17:07:43]  * audio track 2
[17:07:43]    + decoder: English (AC3) (4.1 ch) (track 2, id 0x81bd)
[17:07:43]      + bitrate: 448 kbps, samplerate: 48000 Hz
[17:07:43]    + AC3 Passthru
[17:07:43]  * audio track 3
[17:07:43]    + decoder: English (AC3) (2.0 ch) (Dolby Surround) (track 3, id 0x82bd)
[17:07:43]      + bitrate: 192 kbps, samplerate: 48000 Hz
[17:07:43]    + AC3 Passthru
[17:07:43]  * audio track 4
[17:07:43]    + decoder: English (AC3) (2.0 ch) (Dolby Surround) (track 5, id 0x84bd)
[17:07:43]      + bitrate: 192 kbps, samplerate: 48000 Hz
[17:07:43]    + AC3 Passthru
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[17:07:43] sync: expecting 143428 video frames
[17:07:43] encx264: min-keyint: 30, keyint: 300
[17:07:43] encx264: encoding at constant RF 10.000000
[17:07:43] encx264: unparsed options: level=4.0:ref=8:b-adapt=2:psy-rd=1,0.15:me=umh:direct=auto:deblock=-1,-1:analyse=all:trellis=2:subme=9:vbv-bufsize=31250:vbv-maxrate=25000:rc-lookahead=60
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2
x264 [info]: profile High, level 4.0
[17:07:43] sync: first pts video is 0
[17:07:43] sync: "Chapter 1" (1) at frame 1 time 0
[17:07:43] sync: first pts audio 0x88bd is 0
[17:07:43] sync: first pts audio 0x81bd is 0
[17:07:43] sync: first pts audio 0x82bd is 0
[17:07:43] sync: first pts audio 0x84bd is 0
[17:07:43] sync: first pts subtitle 0x21bd is 0
[17:09:20] sync: "Chapter 2" (2) at frame 3642 time 13667403
[17:12:10] sync: "Chapter 3" (3) at frame 9033 time 33903870
[17:13:39] sync: "Chapter 4" (4) at frame 12314 time 46219923
[17:14:41] sync: "Chapter 5" (5) at frame 14265 time 53543490
[17:16:51] sync: "Chapter 6" (6) at frame 18297 time 68678610
[17:17:53] sync: "Chapter 7" (7) at frame 20085 time 75390315
[17:18:56] sync: "Chapter 8" (8) at frame 21953 time 82402320
[17:21:04] sync: "Chapter 9" (9) at frame 25770 time 96730383
[17:23:12] sync: "Chapter 10" (10) at frame 29189 time 109564455
[17:26:24] sync: "Chapter 11" (11) at frame 35217 time 132192060
[17:29:01] sync: "Chapter 12" (12) at frame 40409 time 151681530
[17:31:03] sync: "Chapter 13" (13) at frame 44629 time 167522355
[17:32:28] sync: "Chapter 14" (14) at frame 47086 time 176745318
[17:34:00] sync: "Chapter 15" (15) at frame 50046 time 187856418
[17:35:00] sync: "Chapter 16" (16) at frame 51842 time 194598153
[17:35:30] sync: "Chapter 17" (17) at frame 52881 time 198498300
[17:36:09] sync: "Chapter 18" (18) at frame 54201 time 203453250
[17:36:42] sync: "Chapter 19" (19) at frame 55385 time 207897690
[17:38:05] sync: "Chapter 20" (20) at frame 57825 time 217056840
[17:40:47] sync: "Chapter 21" (21) at frame 63729 time 239218980
[17:42:07] sync: "Chapter 22" (22) at frame 66414 time 249297798
[17:44:33] sync: "Chapter 23" (23) at frame 71321 time 267717450
[17:45:55] sync: "Chapter 24" (24) at frame 74365 time 279143865
[17:49:46] sync: "Chapter 25" (25) at frame 81462 time 305784228
[17:52:18] sync: "Chapter 26" (26) at frame 86202 time 323577003
[17:53:39] sync: "Chapter 27" (27) at frame 88833 time 333453120
[17:54:28] sync: "Chapter 28" (28) at frame 90545 time 339879540
[17:55:25] sync: "Chapter 29" (29) at frame 92762 time 348201603
[17:56:34] sync: "Chapter 30" (30) at frame 95126 time 357075468
[17:58:55] sync: "Chapter 31" (31) at frame 100153 time 375945570
[17:59:49] sync: "Chapter 32" (32) at frame 102121 time 383332950
[18:01:13] sync: "Chapter 33" (33) at frame 104877 time 393678285
[18:03:11] sync: "Chapter 34" (34) at frame 109057 time 409368960
[18:03:31] sync: "Chapter 35" (35) at frame 109809 time 412191780
[18:04:40] sync: "Chapter 36" (36) at frame 112225 time 421260840
[18:10:50] sync: "Chapter 37" (37) at frame 123525 time 463678215
[18:11:49] sync: "Chapter 38" (38) at frame 125485 time 471035565
[18:12:27] sync: "Chapter 39" (39) at frame 126734 time 475723998
[18:13:16] sync: "Chapter 40" (40) at frame 128422 time 482060328
[18:14:49] sync: "Chapter 41" (41) at frame 131262 time 492720978
[18:16:06] sync: "Chapter 42" (42) at frame 133757 time 502086585
[18:17:42] sync: "Chapter 43" (43) at frame 137278 time 515303538
[18:18:16] sync: "Chapter 44" (44) at frame 138329 time 519248730
[18:19:10] reader: done. 3 scr changes
[18:19:10] work: average encoding speed for job is 33.455620 fps
[18:19:10] vfr: 179449 frames output, 2 dropped and 35904 duped for CFR/PFR
[18:19:10] vfr: lost time: 56310 (0 frames)
[18:19:10] vfr: gained time: 56310 (52 frames) (0 not accounted for)
[18:19:10] dca-decoder done: 561342 frames, 0 decoder errors
[18:19:10] ac3-decoder done: 187115 frames, 0 decoder errors
[18:19:10] ac3-decoder done: 187115 frames, 0 decoder errors
[18:19:10] ac3-decoder done: 187115 frames, 0 decoder errors
[18:19:10] mpeg2video-decoder done: 143560 frames, 0 decoder errors
[18:19:10] sync: got 143560 frames, 143428 expected
[18:19:10] sync: framerate min 23.981 fps, max 29.970 fps, avg 23.976 fps
x264 [info]: frame I:2024  Avg QP: 7.11  size:100930
x264 [info]: frame P:57169 Avg QP:10.12  size: 47837
x264 [info]: frame B:120256 Avg QP:12.76  size: 18712
x264 [info]: consecutive B-frames:  7.9%  2.9% 16.5% 72.8%
x264 [info]: mb I  I16..4: 24.3% 65.5% 10.3%
x264 [info]: mb P  I16..4:  4.5% 17.6%  5.2%  P16..4: 14.6%  8.2% 13.0%  1.5%  1.2%    skip:34.3%
x264 [info]: mb B  I16..4:  0.7%  4.8%  1.2%  B16..8: 24.7%  9.8%  5.1%  direct: 9.4%  skip:44.2%  L0:30.1% L1:25.4% BI:44.6%
x264 [info]: 8x8 transform intra:66.7% inter:52.4%
x264 [info]: direct mvs  spatial:100.0% temporal:0.0%
x264 [info]: coded y,uvDC,uvAC intra: 95.1% 96.7% 95.1% inter: 39.3% 32.5% 20.0%
x264 [info]: i16 v,h,dc,p: 16%  3% 54% 27%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 23% 15% 27%  5%  5%  6%  4%  7%  8%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16% 13% 13%  8% 10% 10%  9% 10% 11%
x264 [info]: i8c dc,h,v,p: 56% 13% 17% 14%
x264 [info]: Weighted P-Frames: Y:4.5% UV:2.4%
x264 [info]: ref P L0: 57.9% 14.5% 11.8%  5.0%  3.8%  2.1%  2.8%  1.7%  0.4%  0.0%
x264 [info]: ref B L0: 81.5% 10.9%  4.0%  1.6%  0.8%  0.7%  0.4%
x264 [info]: ref B L1: 95.5%  4.5%
x264 [info]: kb/s:6933.30
[18:19:10] mux: track 0, 179449 frames, 5189156114 bytes, 6933.11 kbps, fifo 1024
[18:19:10] mux: track 1, 561342 frames, 564710052 bytes, 754.50 kbps, fifo 4096
[18:19:10] mux: track 2, 187115 frames, 335310080 bytes, 448.00 kbps, fifo 2048
[18:19:10] mux: track 3, 187115 frames, 143704320 bytes, 192.00 kbps, fifo 2048
[18:19:10] mux: track 4, 187115 frames, 143704320 bytes, 192.00 kbps, fifo 2048
[18:19:10] mux: track 5, 1463 frames, 1766786 bytes, 2.36 kbps, fifo 16
[18:19:10] libhb: work result = 0

# Encode Completed ...

[rollin_eng]

Your source IS 23.976 FPS

Just leave it as same as source unless you have to change it.

[Demat]

Hi rollin_eng,

Yes, I noticed that in the log (and that's what I got when I had handbrake set on 'same as source') - but something's wrong with that, because the ISO was created from an NTSC DVD with DVD Shrink (which I don't think can change framerates - at least there are no settings I can see) and VLC reports the ISO as 29.97fps..?

https://image.ibb.co/jrfqqH/VLC_framerate.jpg

Sorry if I'm missing the obvious (or if VLC has a known bug or something). #confused..!

Chris

[Woodstock]

If you really want to make a 23.97fps source output as 29.97fps, remove the detelecine filter, which is intended to do the opposite.

But, as rollin points out, the DVD source is not 29.97 on the disk/image.

Both NTSC and PAL often have a 23.97fps video file, and telecine the output to meet the standard.

[Demat]

BTW: I just played the ISO in Kodi, and it reports a source framerate of 59.940 - which I guess is double 29.97 because it's interlaced? Anyway, I wonder if Handbrake is mis-detecting the source framerate when I use the 'same as source' option? It thinks the ISO is 23.97 - which is why I set 29.97 manually.

PS: I ripped my PAL DVDs to ISOs using the same method, and both kodi and VLC report the correct 25fps. If I use 'same as source' in handbrake for converting those ISOs, the output file is also the correct 25fps. So I wonder what is going wrong with the NTSC/29.97 ISOs in handbrake?

[Demat]

If you really want to make a 23.97fps source output as 29.97fps, remove the detelecine filter, which is intended to do the opposite.

But, as rollin points out, the DVD source is not 29.97 on the disk/image.

Both NTSC and PAL often have a 23.97fps video file, and telecine the output to meet the standard.

Thanks Woodstock - reading your last sentence there, I'm guessing that Kodi/VLC are reporting 29.97fps because the telecine applied within the ISO is bringing it up to the standard, from an actual fps of 23.97?
So the solution would be for me to simply go with the 'same as source' 23.97 and keep detelecine set to 'default'? (I have no special reason to try to make it output to a new framerate - I was just going on what VLC/Kodi were reporting)

cheers
Chris

[Woodstock]

One thing that I've "absorbed" over the time I've been reading here is that the run times are ESTIMATES; there is some slop in them, in part because the "frame rates" are not exact.

For example, handbrake estimates how many frames it will find in the source:

[17:07:43] sync: expecting 143428 video frames

and later reports what it actually found:

[18:19:10] sync: got 143560 frames, 143428 expected

and what it output to meet your request:

[18:19:10] vfr: 179449 frames output, 2 dropped and 35904 duped for CFR/PFR

There are 132 frames difference between "expected" and "found", which is about 5.5 seconds, so VLC showing 3 seconds difference isn't that big.

[Demat]

Thanks again Woodstock, I’m new to Handbrake and that last bit from the log you pasted in above really helps. I can see from the number of duped frames that it’s making up the difference between 23.97 and the 29.97 that I’d requested based on the iso playback info. Still amazed that handbrake detects a 23.97 source when its essentially been expanded to meet the 29.97 standard due to the telecine. [EDIT: I understand this better now https://en.m.wikipedia.org/wiki/Telecine]

Based on your earlier post, I will try another conversion from iso to mkv, this time with ‘same as source’ fps selected, and detelecine left on default.

Cheers
Chris

[Demat]

Okay, I've now encoded the movie (NTSC ISO to MKV) with the following settings:

- Picture: zero crop, Anamorphic auto, size 720 x 480
- Filters: Detelecine default, Deinterlace off
- Video: Codec h264, Framerate same as source, constant, Quality: Constant 10 (I want top quality pictures), Encoder preset: Slower, Encoder tune: film, Enc level 4.
All audio = passthru

The resultant mkv is excellent - nothing to choose between it and the original ISO. Framerate is now 23.97 as you suggested it should be, Woodstock.
Sound seems to be in sync and there's no motion blur etc. However, it's still 3 seconds longer than the original movie.. Guess this is just down to the frame rate 'slop' you mentioned re: estimates - also perhaps due to handbrake which has encoded at 23.976215, versus the 23.970029 of the original ISO.

Unless anyone thinks this is a problem (seems to be par for the course from what I've read) I'm going to live with it - as there seems to be no audio sync issues. What do you reckon?

cheers
Chris

[rollin_eng]

Could you please post your logs, instructions can be found here:

https://handbrake.fr/docs/en/latest/hel ... y-log.html

[Demat]

Hi rollin, yes no problem - included below.

Code: Select all

[08:19:18] sync: expecting 143428 video frames
[08:20:22] reader: done. 3 scr changes
[08:20:22] work: average encoding speed for job is 0.000000 fps
[08:20:22] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[08:20:22] vfr: lost time: 0 (0 frames)
[08:20:22] vfr: gained time: 0 (0 frames) (0 not accounted for)
[08:20:22] sync: got 0 frames, 143428 expected
[08:20:22] Subtitle track 0 (id 0x21bd) 'English': 1463 hits (0 forced)
[08:20:22] No candidate detected during subtitle scan
[08:20:22] starting job
[08:20:22] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[08:20:22] Auto Passthru: fallback is AC3
[08:20:22] Auto Passthru: using DTS Passthru for track 1
[08:20:22] Auto Passthru: using AC3 Passthru for track 2
[08:20:22] Auto Passthru: using AC3 Passthru for track 3
[08:20:22] Auto Passthru: using AC3 Passthru for track 4
[08:20:22] job configuration:
[08:20:22]  * source
[08:20:22]    + E:\DVD ISO BACKUPS\Movies\movie.ISO
[08:20:22]    + title 1, chapter(s) 1 to 45
[08:20:22]  * destination
[08:20:22]    + E:\DVD ISO BACKUPS\Movies\movie.mkv
[08:20:22]    + container: Matroska (libavformat)
[08:20:22]      + chapter markers
[08:20:22]  * video track
[08:20:22]    + decoder: mpeg2video
[08:20:22]      + bitrate 9800 kbps
[08:20:22]    + filters
[08:20:22]      + Detelecine (pullup) ()
[08:20:22]      + Framerate Shaper (mode=1)
[08:20:22]        + frame rate: 23.976 fps -> constant 23.976 fps
[08:20:22]      + Crop and Scale (width=720:height=480:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[08:20:22]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[08:20:22]    + Output geometry
[08:20:22]      + storage dimensions: 720 x 480
[08:20:22]      + pixel aspect ratio: 32 : 27
[08:20:22]      + display dimensions: 853 x 480
[08:20:22]    + encoder: H.264 (libx264)
[08:20:22]      + preset:  slower
[08:20:22]      + tune:    film
[08:20:22]      + profile: high
[08:20:22]      + level:   4.0
[08:20:22]      + quality: 10.00 (RF)
[08:20:22]  * subtitle track 1, English (track 0, id 0x21bd) Picture [VOBSUB] -> Passthrough
[08:20:22]  * audio track 1
[08:20:22]    + decoder: English (DTS) (5.1 ch) (track 1, id 0x88bd)
[08:20:22]      + bitrate: 768 kbps, samplerate: 48000 Hz
[08:20:22]    + DTS Passthru
[08:20:22]  * audio track 2
[08:20:22]    + decoder: English (AC3) (4.1 ch) (track 2, id 0x81bd)
[08:20:22]      + bitrate: 448 kbps, samplerate: 48000 Hz
[08:20:22]    + AC3 Passthru
[08:20:22]  * audio track 3
[08:20:22]    + decoder: English (AC3) (2.0 ch) (Dolby Surround) (track 3, id 0x82bd)
[08:20:22]      + bitrate: 192 kbps, samplerate: 48000 Hz
[08:20:22]    + AC3 Passthru
[08:20:22]  * audio track 4
[08:20:22]    + decoder: English (AC3) (2.0 ch) (Dolby Surround) (track 5, id 0x84bd)
[08:20:22]      + bitrate: 192 kbps, samplerate: 48000 Hz
[08:20:22]    + AC3 Passthru
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[08:20:22] sync: expecting 143428 video frames
[08:20:22] encx264: min-keyint: 24, keyint: 240
[08:20:22] encx264: encoding at constant RF 10.000000
[08:20:22] encx264: unparsed options: level=4.0:subme=9:ref=8:b-adapt=2:direct=auto:deblock=-1,-1:analyse=all:me=umh:trellis=2:psy-rd=1,0.15:vbv-bufsize=31250:vbv-maxrate=25000:rc-lookahead=60
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2
x264 [info]: profile High, level 4.0
[08:20:22] sync: first pts video is 0
[08:20:22] sync: "Chapter 1" (1) at frame 1 time 0
[08:20:22] sync: first pts audio 0x88bd is 0
[08:20:22] sync: first pts audio 0x81bd is 0
[08:20:22] sync: first pts audio 0x82bd is 0
[08:20:22] sync: first pts audio 0x84bd is 0
[08:20:22] sync: first pts subtitle 0x21bd is 0
[08:21:52] sync: "Chapter 2" (2) at frame 3642 time 13667403
[08:24:46] sync: "Chapter 3" (3) at frame 9033 time 33903870
[08:26:20] sync: "Chapter 4" (4) at frame 12314 time 46219923
[08:27:25] sync: "Chapter 5" (5) at frame 14265 time 53543490
[08:29:34] sync: "Chapter 6" (6) at frame 18297 time 68678610
[08:30:39] sync: "Chapter 7" (7) at frame 20085 time 75390315
[08:31:46] sync: "Chapter 8" (8) at frame 21953 time 82402320
[08:33:54] sync: "Chapter 9" (9) at frame 25770 time 96730383
[08:36:06] sync: "Chapter 10" (10) at frame 29189 time 109564455
[08:39:21] sync: "Chapter 11" (11) at frame 35217 time 132192060
[08:41:55] sync: "Chapter 12" (12) at frame 40409 time 151681530
[08:43:50] sync: "Chapter 13" (13) at frame 44629 time 167522355
[08:45:13] sync: "Chapter 14" (14) at frame 47086 time 176745318
[08:46:41] sync: "Chapter 15" (15) at frame 50046 time 187856418
[08:47:39] sync: "Chapter 16" (16) at frame 51842 time 194598153
[08:48:08] sync: "Chapter 17" (17) at frame 52881 time 198498300
[08:48:44] sync: "Chapter 18" (18) at frame 54201 time 203453250
[08:49:14] sync: "Chapter 19" (19) at frame 55385 time 207897690
[08:50:34] sync: "Chapter 20" (20) at frame 57825 time 217056840
[08:53:05] sync: "Chapter 21" (21) at frame 63729 time 239218980
[08:54:17] sync: "Chapter 22" (22) at frame 66414 time 249297798
[08:56:36] sync: "Chapter 23" (23) at frame 71321 time 267717450
[08:57:53] sync: "Chapter 24" (24) at frame 74365 time 279143865
[09:01:37] sync: "Chapter 25" (25) at frame 81462 time 305784228
[09:04:03] sync: "Chapter 26" (26) at frame 86202 time 323577003
[09:05:20] sync: "Chapter 27" (27) at frame 88833 time 333453120
[09:06:06] sync: "Chapter 28" (28) at frame 90545 time 339879540
[09:07:01] sync: "Chapter 29" (29) at frame 92762 time 348201603
[09:08:07] sync: "Chapter 30" (30) at frame 95126 time 357075468
[09:10:30] sync: "Chapter 31" (31) at frame 100153 time 375945570
[09:11:25] sync: "Chapter 32" (32) at frame 102121 time 383332950
[09:12:51] sync: "Chapter 33" (33) at frame 104877 time 393678285
[09:14:55] sync: "Chapter 34" (34) at frame 109057 time 409368960
[09:15:15] sync: "Chapter 35" (35) at frame 109809 time 412191780
[09:16:27] sync: "Chapter 36" (36) at frame 112225 time 421260840
[09:22:54] sync: "Chapter 37" (37) at frame 123525 time 463678215
[09:23:55] sync: "Chapter 38" (38) at frame 125485 time 471035565
[09:24:31] sync: "Chapter 39" (39) at frame 126734 time 475723998
[09:25:20] sync: "Chapter 40" (40) at frame 128422 time 482060328
[09:26:56] sync: "Chapter 41" (41) at frame 131262 time 492720978
[09:28:15] sync: "Chapter 42" (42) at frame 133757 time 502086585
[09:29:49] sync: "Chapter 43" (43) at frame 137278 time 515303538
[09:30:23] sync: "Chapter 44" (44) at frame 138329 time 519248730
[09:31:19] reader: done. 3 scr changes
[09:31:20] work: average encoding speed for job is 33.683235 fps
[09:31:20] vfr: 143560 frames output, 2 dropped and 15 duped for CFR/PFR
[09:31:20] vfr: lost time: 56310 (0 frames)
[09:31:20] vfr: gained time: 56310 (52 frames) (0 not accounted for)
[09:31:20] dca-decoder done: 561342 frames, 0 decoder errors
[09:31:20] ac3-decoder done: 187115 frames, 0 decoder errors
[09:31:20] ac3-decoder done: 187115 frames, 0 decoder errors
[09:31:20] ac3-decoder done: 187115 frames, 0 decoder errors
[09:31:20] mpeg2video-decoder done: 143560 frames, 0 decoder errors
[09:31:20] sync: got 143560 frames, 143428 expected
[09:31:20] sync: framerate min 23.981 fps, max 29.970 fps, avg 23.976 fps
x264 [info]: frame I:1971  Avg QP: 6.96  size:102863
x264 [info]: frame P:42683 Avg QP: 9.91  size: 60550
x264 [info]: frame B:98906 Avg QP:12.60  size: 26662
x264 [info]: consecutive B-frames:  2.4%  5.0% 36.5% 56.1%
x264 [info]: mb I  I16..4: 24.6% 64.9% 10.5%
x264 [info]: mb P  I16..4:  6.0% 21.8%  7.0%  P16..4: 14.3% 10.0% 15.7%  2.0%  1.6%    skip:21.7%
x264 [info]: mb B  I16..4:  1.2%  6.2%  1.9%  B16..8: 27.6% 12.6%  6.8%  direct:12.3%  skip:31.3%  L0:27.6% L1:24.3% BI:48.1%
x264 [info]: 8x8 transform intra:64.3% inter:51.6%
x264 [info]: direct mvs  spatial:98.9% temporal:1.1%
x264 [info]: coded y,uvDC,uvAC intra: 95.2% 96.8% 95.5% inter: 52.5% 43.6% 28.2%
x264 [info]: i16 v,h,dc,p: 14%  3% 55% 27%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 23% 16% 27%  5%  5%  6%  4%  7%  8%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16% 13% 13%  8% 10% 10%  9% 10% 11%
x264 [info]: i8c dc,h,v,p: 57% 13% 17% 14%
x264 [info]: Weighted P-Frames: Y:5.7% UV:3.1%
x264 [info]: ref P L0: 56.1% 15.7% 12.9%  4.1%  4.0%  2.5%  2.6%  1.8%  0.1%  0.0%
x264 [info]: ref B L0: 83.2% 11.1%  2.6%  1.3%  0.9%  0.6%  0.3%
x264 [info]: ref B L1: 97.3%  2.7%
x264 [info]: kb/s:7247.21
[09:31:20] mux: track 0, 143560 frames, 5424136581 bytes, 7247.05 kbps, fifo 1024
[09:31:20] mux: track 1, 561342 frames, 564710052 bytes, 754.49 kbps, fifo 4096
[09:31:20] mux: track 2, 187115 frames, 335310080 bytes, 448.00 kbps, fifo 2048
[09:31:20] mux: track 3, 187115 frames, 143704320 bytes, 192.00 kbps, fifo 2048
[09:31:20] mux: track 4, 187115 frames, 143704320 bytes, 192.00 kbps, fifo 2048
[09:31:20] mux: track 5, 1463 frames, 1766786 bytes, 2.36 kbps, fifo 16
[09:31:20] libhb: work result = 0

# Encode Completed ...

[rollin_eng]

What happenes if you set the framerate as 30 fps peak framerate?

[Demat]

ok, I will give that a go now.

[Demat]

Hi rollin - I tried the exact same settings as before, but with the framerate set to 30 fps, peak, - the output file is 23.976215 as before, and the runtime of the mkv is (at least according to VLC) 4 seconds longer than the original ISO. No picture or sound sync problems that I can see though. I just wonder if the difference is due to a number of frames added (to reach 23.976215 from the original 23.970029 source) - by a rough calculation it would add up to about 2 extra seconds over the course of the movie, I guess the rest could just be a margin of error in VLC's reporting of mkv vs iso..

Code: Select all

 [18:55:48]    + container: Matroska (libavformat)
[18:55:48]    + filters
[18:55:48]      + Detelecine (pullup) ()
[18:55:48]      + Framerate Shaper (mode=2:rate=27000000/900000)
[18:55:48]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[18:55:48]      + Subtitle renderer ()
[18:55:48]      + Crop and Scale (width=720:height=480:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[18:55:48]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[18:55:48]    + Output geometry
[18:55:48]      + storage dimensions: 720 x 480
[18:55:48]      + pixel aspect ratio: 32 : 27
[18:55:48]      + display dimensions: 853 x 480
[18:55:48]    + encoder: H.264 (libx264)
[18:55:48]      + preset:  slower
[18:55:48]      + tune:    film,fastdecode
[18:55:48]      + profile: high
[18:55:48]      + level:   4.0
[18:55:48]      + quality: 10.00 (RF)[\code]

[rollin_eng]

Could you please post your complete log, not just part of it.

[Demat]

Yes, I only posted the segment that I thought you might need (but also to avoid confusion as I left my hard drive with the original ISO on it at work. So, this is a movie with the same characteristics, NTSC 23.970029 etc - it produces the same extended runtime issue from handbrake in any case).
Here's the log:

Code: Select all

 HandBrake 1.0.7 (2017040900) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz
Ram: 32707 MB, 
GPU Information:
  NVIDIA GeForce GTX 780 - 23.21.13.8813
Screen: 2560x1600
Temp Dir: C:\Users\user\AppData\Local\Temp\
Install Dir: F:\Program Files\Handbrake
Data Dir: C:\Users\user\AppData\Roaming\HandBrake Team\HandBrake\1.0.7.0

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


# Starting Encode ...

[18:55:01] hb_init: starting libhb thread
[18:55:01] 1 job(s) to process
[18:55:01] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0.0,
        "Encoder": 1125984256,
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      },
      {
        "DRC": 0.0,
        "Encoder": 1125984256,
        "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"
      },
      {
        "Name": "Chapter 14"
      },
      {
        "Name": "Chapter 15"
      },
      {
        "Name": "Chapter 16"
      },
      {
        "Name": "Chapter 17"
      },
      {
        "Name": "Chapter 18"
      },
      {
        "Name": "Chapter 19"
      },
      {
        "Name": "Chapter 20"
      },
      {
        "Name": "Chapter 21"
      },
      {
        "Name": "Chapter 22"
      },
      {
        "Name": "Chapter 23"
      },
      {
        "Name": "Chapter 24"
      },
      {
        "Name": "Chapter 25"
      },
      {
        "Name": "Chapter 26"
      },
      {
        "Name": "Chapter 27"
      },
      {
        "Name": "Chapter 28"
      },
      {
        "Name": "Chapter 29"
      },
      {
        "Name": "Chapter 30"
      }
    ],
    "ChapterMarkers": true,
    "File": "E:\\DVD ISO BACKUPS2\\Movies\\Movie2\\movie2-30fps peak test.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 2097152
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 2,
        "Settings": {}
      },
      {
        "ID": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "480",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 32,
    "Den": 27
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 30
    },
    "Title": 11,
    "Path": "E:\\DVD ISO BACKUPS2\\Movies\\Movie2\\Movie2.ISO"
  },
  "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": 65536,
    "Level": "4.0",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "slower",
    "Profile": "high",
    "Quality": 10.0,
    "Tune": "film,fastdecode",
    "OpenCL": false,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[18:55:01] CPU: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz
[18:55:01]  - Intel microarchitecture Haswell
[18:55:01]  - logical processor count: 8
[18:55:01] Intel Quick Sync Video support: no
[18:55:01] hb_scan: path=E:\DVD ISO BACKUPS2\Movies\Movie2\Movie2.ISO, title_index=11
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
[18:55:01] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: MOVIE2
libdvdnav: DVD Serial Number: 00000000
libdvdnav: DVD Title (Alternative): 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[18:55:01] scan: DVD has 28 title(s)
[18:55:01] scan: scanning title 11
[18:55:01] scan: opening IFO for VTS 8
[18:55:01] scan: duration is 01:45:03 (6303533 ms)
[18:55:01] pgc_id: 1, pgn: 1: pgc: 0000000043D806A0
[18:55:01] scan: vts=8, ttn=1, cells=0->30, blocks=0->2361625, 2361626 blocks
[18:55:01] scan: checking audio 1
[18:55:01] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[18:55:01] scan: checking audio 2
[18:55:01] scan: id=0x81bd, lang=English (AC3), 3cc=eng ext=3
[18:55:01] scan: checking subtitle 1
[18:55:01] scan: id=0x20bd, lang=English, 3cc=eng ext=1
[18:55:01] scan: checking subtitle 2
[18:55:01] scan: id=0x21bd, lang=English, 3cc=eng ext=0
[18:55:01] scan: checking subtitle 3
[18:55:01] scan: id=0x22bd, lang=English (Director's Commentary), 3cc=eng ext=13
[18:55:01] scan: checking subtitle 4
[18:55:01] scan: id=0x22bd, lang=English (Director's Commentary with bigger size character), 3cc=eng ext=14
[18:55:01] scan: title 11 has 30 chapters
[18:55:01] scan: chap 1 c=0->0, b=0->65405 (65406), 166233 ms
[18:55:01] scan: chap 2 c=1->1, b=65406->146035 (80630), 231266 ms
[18:55:01] scan: chap 3 c=2->2, b=146036->299377 (153342), 394100 ms
[18:55:01] scan: chap 4 c=3->3, b=299378->355163 (55786), 152767 ms
[18:55:01] scan: chap 5 c=4->4, b=355164->376316 (21153), 58867 ms
[18:55:01] scan: chap 6 c=5->5, b=376317->417554 (41238), 110767 ms
[18:55:01] scan: chap 7 c=6->6, b=417555->469181 (51627), 137867 ms
[18:55:01] scan: chap 8 c=7->7, b=469182->489194 (20013), 55734 ms
[18:55:01] scan: chap 9 c=8->8, b=489195->536206 (47012), 129767 ms
[18:55:01] scan: chap 10 c=9->9, b=536207->635925 (99719), 275500 ms
[18:55:01] scan: chap 11 c=10->10, b=635926->727366 (91441), 238633 ms
[18:55:01] scan: chap 12 c=11->11, b=727367->778548 (51182), 137233 ms
[18:55:01] scan: chap 13 c=12->12, b=778549->873925 (95377), 254500 ms
[18:55:01] scan: chap 14 c=13->13, b=873926->1036037 (162112), 420767 ms
[18:55:01] scan: chap 15 c=14->15, b=1036038->1092128 (56091), 158633 ms
[18:55:01] scan: chap 16 c=16->16, b=1092129->1165936 (73808), 208967 ms
[18:55:01] scan: chap 17 c=17->17, b=1165937->1205303 (39367), 97600 ms
[18:55:01] scan: chap 18 c=18->18, b=1205304->1234467 (29164), 70734 ms
[18:55:01] scan: chap 19 c=19->19, b=1234468->1329204 (94737), 248400 ms
[18:55:01] scan: chap 20 c=20->20, b=1329205->1447907 (118703), 329233 ms
[18:55:01] scan: chap 21 c=21->21, b=1447908->1582368 (134461), 358834 ms
[18:55:01] scan: chap 22 c=22->22, b=1582369->1649296 (66928), 176500 ms
[18:55:01] scan: chap 23 c=23->23, b=1649297->1674245 (24949), 68367 ms
[18:55:01] scan: chap 24 c=24->24, b=1674246->1765853 (91608), 251633 ms
[18:55:01] scan: chap 25 c=25->25, b=1765854->1911103 (145250), 377734 ms
[18:55:01] scan: chap 26 c=26->26, b=1911104->2032239 (121136), 326767 ms
[18:55:01] scan: chap 27 c=27->27, b=2032240->2098503 (66264), 184867 ms
[18:55:01] scan: chap 28 c=28->28, b=2098504->2361482 (262979), 678600 ms
[18:55:01] scan: chap 29 c=29->29, b=2361483->2361561 (79), 1333 ms
[18:55:01] scan: chap 30 c=30->30, b=2361562->2361625 (64), 1333 ms
[18:55:01] scan: aspect = 16:9
[18:55:01] scan: decoding previews for title 11
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[18:55:01] scan: title angle(s) 1
[ac3 @ 00000000438e26a0] frame sync error
[18:55:01] scan: audio 0x80bd: ac3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[ac3 @ 00000000438e26a0] frame sync error
[18:55:01] scan: audio 0x81bd: ac3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (2.0 ch) (Dolby Surround)
[18:55:01] scan: 10 previews, 720x480, 23.976 fps, autocrop = 64/66/4/8, aspect 16:9, PAR 32:27
[18:55:01] libhb: scan thread found 1 valid title(s)
[18:55:01] starting job
[18:55:01] job configuration:
[18:55:01]  * source
[18:55:01]    + E:\DVD ISO BACKUPS2\Movies\Movie2\Movie2.ISO
[18:55:01]    + title 11, chapter(s) 1 to 30
[18:55:01]  * destination
[18:55:01]    + E:\DVD ISO BACKUPS2\Movies\Movie2\Movie2-30fps peak test.mkv
[18:55:01]    + container: Matroska (libavformat)
[18:55:01]      + chapter markers
[18:55:01]  * video track
[18:55:01]    + decoder: mpeg2video
[18:55:01]      + bitrate 6551 kbps
[18:55:01]    + filters
[18:55:01]      + Detelecine (pullup) ()
[18:55:01]      + Framerate Shaper (mode=2:rate=27000000/900000)
[18:55:01]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[18:55:01]      + Crop and Scale (width=720:height=480:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[18:55:01]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[18:55:01]    + Output geometry
[18:55:01]      + storage dimensions: 720 x 480
[18:55:01]      + pixel aspect ratio: 32 : 27
[18:55:01]      + display dimensions: 853 x 480
[18:55:01]  * Foreign Audio Search: Render/Burn-in, Forced Only
[18:55:01]    + subtitle, English (track 0, id 0x20bd) Picture [VOBSUB]
[18:55:01]    + subtitle, English (track 1, id 0x21bd) Picture [VOBSUB]
[18:55:01]    + subtitle, English (Director's Commentary) (track 2, id 0x22bd) Picture [VOBSUB]
[18:55:01]    + subtitle, English (Director's Commentary with bigger size character) (track 3, id 0x22bd) Picture [VOBSUB]
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: Movie2
libdvdnav: DVD Serial Number: 00000000
libdvdnav: DVD Title (Alternative): 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[18:55:01] sync: expecting 151133 video frames
[18:55:48] reader: done. 1 scr changes
[18:55:48] work: average encoding speed for job is 0.000000 fps
[18:55:48] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[18:55:48] vfr: lost time: 0 (0 frames)
[18:55:48] vfr: gained time: 0 (0 frames) (0 not accounted for)
[18:55:48] sync: got 0 frames, 151133 expected
[18:55:48] Subtitle track 0 (id 0x20bd) 'English': 1260 hits (0 forced)
[18:55:48] Subtitle track 1 (id 0x21bd) 'English': 685 hits (0 forced)
[18:55:48] Subtitle track 2 (id 0x22bd) 'English (Director's Commentary)': 91158 hits (0 forced)
[18:55:48] Subtitle track 3 (id 0x22bd) 'English (Director's Commentary with bigger size character)': 91158 hits (0 forced)
[18:55:48] Found a subtitle candidate with id 0x21bd
[18:55:48] starting job
[18:55:48] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[18:55:48] Auto Passthru: fallback is AC3
[18:55:48] Auto Passthru: using AC3 Passthru for track 1
[18:55:48] Auto Passthru: using AC3 Passthru for track 2
[18:55:48] job configuration:
[18:55:48]  * source
[18:55:48]    + E:\DVD ISO BACKUPS2\Movies\Movie2\Movie2.ISO
[18:55:48]    + title 11, chapter(s) 1 to 30
[18:55:48]  * destination
[18:55:48]    + E:\DVD ISO BACKUPS2\Movies\Movie2\Movie2-30fps peak test.mkv
[18:55:48]    + container: Matroska (libavformat)
[18:55:48]      + chapter markers
[18:55:48]  * video track
[18:55:48]    + decoder: mpeg2video
[18:55:48]      + bitrate 6551 kbps
[18:55:48]    + filters
[18:55:48]      + Detelecine (pullup) ()
[18:55:48]      + Framerate Shaper (mode=2:rate=27000000/900000)
[18:55:48]        + frame rate: 23.976 fps -> peak rate limited to 30.000 fps
[18:55:48]      + Subtitle renderer ()
[18:55:48]      + Crop and Scale (width=720:height=480:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[18:55:48]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[18:55:48]    + Output geometry
[18:55:48]      + storage dimensions: 720 x 480
[18:55:48]      + pixel aspect ratio: 32 : 27
[18:55:48]      + display dimensions: 853 x 480
[18:55:48]    + encoder: H.264 (libx264)
[18:55:48]      + preset:  slower
[18:55:48]      + tune:    film,fastdecode
[18:55:48]      + profile: high
[18:55:48]      + level:   4.0
[18:55:48]      + quality: 10.00 (RF)
[18:55:48]  * subtitle track 1, English (track 1, id 0x21bd) Picture [VOBSUB] -> Render/Burn-in
[18:55:48]  * subtitle track 2, English (track 0, id 0x20bd) Picture [VOBSUB] -> Passthrough
[18:55:48]  * audio track 1
[18:55:48]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[18:55:48]      + bitrate: 448 kbps, samplerate: 48000 Hz
[18:55:48]    + AC3 Passthru
[18:55:48]  * audio track 2
[18:55:48]    + decoder: English (AC3) (Director's Commentary 1) (2.0 ch) (Dolby Surround) (track 2, id 0x81bd)
[18:55:48]      + bitrate: 192 kbps, samplerate: 48000 Hz
[18:55:48]    + AC3 Passthru
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: MOVIE2
libdvdnav: DVD Serial Number: 00000000
libdvdnav: DVD Title (Alternative): 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[18:55:48] sync: expecting 151133 video frames
[18:55:48] encx264: min-keyint: 24, keyint: 240
[18:55:48] encx264: encoding at constant RF 10.000000
[18:55:48] encx264: unparsed options: subme=9:level=4.0:cabac=0:ref=8:b-adapt=2:direct=auto:analyse=all:weightb=0:rc-lookahead=60:no-deblock=1:psy-rd=1,0.15:weightp=0:me=umh:trellis=2:vbv-bufsize=31250:vbv-maxrate=25000
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2
x264 [info]: profile High, level 4.0
[18:55:48] sync: first pts audio 0x80bd is 0
[18:55:48] sync: first pts audio 0x81bd is 0
[18:55:48] sync: first pts video is 6006
[18:55:48] sync: "Chapter 1" (1) at frame 1 time 6006
[18:55:49] sync: first pts subtitle 0x21bd is 270270
[18:57:40] sync: first pts subtitle 0x20bd is 12969957
[18:57:54] sync: "Chapter 2" (2) at frame 3991 time 14983468
[19:00:38] sync: "Chapter 3" (3) at frame 9541 time 35816781
[19:03:02] 600.625000s: Film -> Video
[19:03:02] 600.666748s: Video -> Film
[19:05:27] sync: "Chapter 4" (4) at frame 19000 time 71323502
[19:07:15] sync: "Chapter 5" (5) at frame 22666 time 85084749
[19:07:58] sync: "Chapter 6" (6) at frame 24079 time 90388798
[19:09:25] sync: "Chapter 7" (7) at frame 26737 time 100366266
[19:10:27] 1201.225098s: Film -> Video
[19:10:27] 1201.266724s: Video -> Film
[19:11:05] sync: "Chapter 8" (8) at frame 30046 time 112787424
[19:11:39] sync: "Chapter 9" (9) at frame 31384 time 117809942
[19:13:01] sync: "Chapter 10" (10) at frame 34498 time 129499119
[19:16:18] sync: "Chapter 11" (11) at frame 41110 time 154318914
[19:17:20] 1801.825073s: Film -> Video
[19:17:20] 1801.866699s: Video -> Film
[19:19:08] sync: "Chapter 12" (12) at frame 46837 time 175816641
[19:20:42] sync: "Chapter 13" (13) at frame 50131 time 188181493
[19:23:52] sync: "Chapter 14" (14) at frame 56239 time 211109398
[19:24:32] 2402.425049s: Film -> Video
[19:24:32] 2402.466553s: Video -> Film
[19:29:04] sync: "Chapter 15" (15) at frame 66337 time 249014766
[19:30:43] 2917.064209s: Film -> Video
[19:30:43] 2917.147461s: Video -> Film
[19:30:48] sync: "Chapter 16" (16) at frame 70145 time 263309046
[19:31:42] 3002.966797s: Film -> Video
[19:31:42] 3003.050049s: Video -> Film
[19:33:15] sync: "Chapter 17" (17) at frame 75172 time 282177645
[19:34:34] sync: "Chapter 18" (18) at frame 77503 time 290927637
[19:35:28] 3302.782959s: Film -> Video
[19:35:28] 3302.966309s: Video -> Film
[19:35:29] sync: "Chapter 19" (19) at frame 79202 time 297300753
[19:38:35] sync: "Chapter 20" (20) at frame 85163 time 319676857
[19:42:24] sync: "Chapter 21" (21) at frame 93065 time 349338990
[19:42:41] 3903.299561s: Film -> Video
[19:42:41] 3903.382813s: Video -> Film
[19:46:45] sync: "Chapter 22" (22) at frame 101677 time 381664783
[19:49:03] sync: "Chapter 23" (23) at frame 105913 time 397565668
[19:49:53] sync: "Chapter 24" (24) at frame 107554 time 403725572
[19:50:07] 4503.940918s: Film -> Video
[19:50:07] 4503.982910s: Video -> Film
[19:52:56] sync: "Chapter 25" (25) at frame 113593 time 426394468
[19:57:41] 5104.541016s: Film -> Video
[19:57:41] 5104.582520s: Video -> Film
[19:57:49] sync: "Chapter 26" (26) at frame 122659 time 460425966
[20:01:38] sync: "Chapter 27" (27) at frame 130501 time 489862873
[20:03:41] sync: "Chapter 28" (28) at frame 134938 time 506518262
[20:04:42] 5705.141113s: Film -> Video
[20:04:42] 5705.183105s: Video -> Film
[20:11:29] reader: done. 1 scr changes
[20:11:31] work: average encoding speed for job is 33.286892 fps
[20:11:31] vfr: 151212 frames output, 0 dropped and 0 duped for CFR/PFR
[20:11:31] vfr: lost time: 30780 (0 frames)
[20:11:31] vfr: gained time: 30780 (36 frames) (0 not accounted for)
[20:11:31] ac3-decoder done: 197089 frames, 0 decoder errors
[20:11:31] ac3-decoder done: 197089 frames, 0 decoder errors
[20:11:31] mpeg2video-decoder done: 151223 frames, 0 decoder errors
[20:11:31] sync: got 151221 frames, 151133 expected
[20:11:31] sync: framerate min 14.983 fps, max 29.970 fps, avg 23.976 fps
x264 [info]: frame I:1254  Avg QP: 5.11  size: 82663
x264 [info]: frame P:44811 Avg QP: 7.41  size: 43939
x264 [info]: frame B:105147 Avg QP:10.49  size: 15464
x264 [info]: consecutive B-frames:  2.0%  4.0% 35.7% 58.4%
x264 [info]: mb I  I16..4: 28.5% 61.1% 10.4%
x264 [info]: mb P  I16..4:  1.2% 12.3%  1.5%  P16..4: 24.8% 16.2% 15.9%  1.4%  0.8%    skip:25.9%
x264 [info]: mb B  I16..4:  0.2%  4.5%  0.4%  B16..8: 33.4% 14.4%  4.8%  direct: 7.4%  skip:34.9%  L0:41.2% L1:38.2% BI:20.7%
x264 [info]: 8x8 transform intra:82.4% inter:63.5%
x264 [info]: direct mvs  spatial:99.6% temporal:0.4%
x264 [info]: coded y,uvDC,uvAC intra: 89.3% 89.6% 82.3% inter: 42.0% 33.5% 24.8%
x264 [info]: i16 v,h,dc,p: 44% 27% 10% 18%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 20% 11% 25%  6%  7%  8%  7%  8%  8%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 17% 11%  5%  9% 13% 14% 11% 11%  9%
x264 [info]: i8c dc,h,v,p: 59% 16% 19%  6%
x264 [info]: ref P L0: 66.8% 11.8%  6.8%  4.3%  3.7%  3.3%  3.3%  0.1%
x264 [info]: ref B L0: 77.7% 12.2%  4.0%  2.2%  1.9%  1.4%  0.7%
x264 [info]: ref B L1: 96.9%  3.1%
x264 [info]: kb/s:4691.28
[20:11:31] mux: track 0, 151212 frames, 3698554201 bytes, 4691.20 kbps, fifo 1024
[20:11:31] mux: track 1, 197089 frames, 353183488 bytes, 447.97 kbps, fifo 1024
[20:11:31] mux: track 2, 197089 frames, 151364352 bytes, 191.99 kbps, fifo 1024
[20:11:31] mux: track 3, 1260 frames, 3752672 bytes, 4.76 kbps, fifo 16
[20:11:31] libhb: work result = 0

# Encode Completed ...

[\code]

[Demat]

Hi rollin / woodstock and all,

Quick addition which might be of interest - I've just encoded a 25fps pal ISO using exactly the same settings as above (fps=same as source, constant, etc) and the runtime of the resultant mkv and the ISO are identical [log below for comaprison]. Both are 25fps. So, I'm guessing that the reason I have an extended runtime on an mkv generated from an NTSC ISO is that the source framerate is 23.970029, but handbrake is creating 23.976215.

[EDIT] I think I may have identified the issue - it could very well be VLC player itself - there are a few reports, if you dig around, of VLC not reporting the correct duration of MPEG / DVDs. This is the most recent one: https://trac.videolan.org/vlc/ticket/17882. And here's an older one: https://trac.videolan.org/vlc/ticket/6912

I decided to test this with a new DVD - I decided to rip my NTSC copy of 2001 A Space Odyssey using DVD shrink (same method as all my other backups) and then handbrake using the same settings to create an mkv. The runtime on the original according to VLC is 2:28:30. My mkv, according to VLC, is 2:28:39 - a full 9 seconds longer. So, I decided to open them both at once and hit play to see if they would fall out of sync with each other over time.

This is the really weird bit - the start of 2001 is a black screen with music for the first 3 minutes - the time counter on VLC for the ISO barely moves while the screen is black. By the time we hit 3 minutes on the mkv, the ISO counter reads 00:35!!! (screengrab: https://image.ibb.co/cCViFS/vlc1.jpg)
And, as soon as the picture appears, the counter starts to counts normally. I guess this is one for the VLC support forum.. Thanks guys - hopefully this thread might help someone else who has the same issue after using handbrake with VLC.


cheers
Chris

Code: Select all

HandBrake 1.0.7 (2017040900) - 64bit
OS: Microsoft Windows NT 6.1.7601 Service Pack 1 - 64bit
CPU: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz
Ram: 32707 MB, 
GPU Information:
  NVIDIA GeForce GTX 780 - 23.21.13.8813
Screen: 2560x1600
Temp Dir: C:\Users\user\AppData\Local\Temp\
Install Dir: F:\Program Files\Handbrake
Data Dir: C:\Users\user\AppData\Roaming\HandBrake Team\HandBrake\1.0.7.0

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


# Starting Encode ...

[12:44:59] hb_init: starting libhb thread
[12:44:59] 1 job(s) to process
[12:44:59] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0.0,
        "Encoder": 1125984256,
        "Gain": 0.0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      },
      {
        "DRC": 0.0,
        "Encoder": 1125984256,
        "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"
      },
      {
        "Name": "Chapter 14"
      },
      {
        "Name": "Chapter 15"
      },
      {
        "Name": "Chapter 16"
      },
      {
        "Name": "Chapter 17"
      },
      {
        "Name": "Chapter 18"
      },
      {
        "Name": "Chapter 19"
      },
      {
        "Name": "Chapter 20"
      },
      {
        "Name": "Chapter 21"
      },
      {
        "Name": "Chapter 22"
      },
      {
        "Name": "Chapter 23"
      },
      {
        "Name": "Chapter 24"
      },
      {
        "Name": "Chapter 25"
      },
      {
        "Name": "Chapter 26"
      },
      {
        "Name": "Chapter 27"
      },
      {
        "Name": "Chapter 28"
      },
      {
        "Name": "Chapter 29"
      },
      {
        "Name": "Chapter 30"
      },
      {
        "Name": "Chapter 31"
      },
      {
        "Name": "Chapter 32"
      },
      {
        "Name": "Chapter 33"
      },
      {
        "Name": "Chapter 34"
      },
      {
        "Name": "Chapter 35"
      },
      {
        "Name": "Chapter 36"
      },
      {
        "Name": "Chapter 37"
      },
      {
        "Name": "Chapter 38"
      }
    ],
    "ChapterMarkers": true,
    "File": "E:\\DVD ISO BACKUPS\\Movies\\PAL1.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 2097152
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 2,
        "Settings": {}
      },
      {
        "ID": 11,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "576",
          "width": "720"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "1"
        }
      }
    ]
  },
  "PAR": {
    "Num": 64,
    "Den": 45
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 38
    },
    "Title": 1,
    "Path": "E:\\DVD ISO BACKUPS\\Movies\\PAL1.ISO"
  },
  "Subtitle": {
    "Search": {
      "Burn": false,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": [
      {
        "Burn": false,
        "Default": false,
        "Forced": false,
        "ID": 1,
        "Offset": 0,
        "Track": 0
      },
      {
        "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": "",
    "Preset": "slower",
    "Profile": "high",
    "Quality": 10.0,
    "Tune": "film",
    "OpenCL": false,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[12:44:59] CPU: Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz
[12:44:59]  - Intel microarchitecture Haswell
[12:44:59]  - logical processor count: 8
[12:44:59] Intel Quick Sync Video support: no
[12:44:59] hb_scan: path=E:\DVD ISO BACKUPS\Movies\PAL1.ISO, title_index=1
udfread ERROR: unregonized string encoding 0
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
[12:44:59] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PAL1
libdvdnav: DVD Serial Number: 2466054B
libdvdnav: DVD Title (Alternative): PAL1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
[12:44:59] scan: DVD has 3 title(s)
[12:44:59] scan: scanning title 1
[12:44:59] scan: opening IFO for VTS 1
[12:44:59] scan: duration is 01:41:28 (6088600 ms)
[12:44:59] pgc_id: 1, pgn: 1: pgc: 0000000000EE1430
[12:44:59] scan: vts=1, ttn=1, cells=0->37, blocks=0->1832866, 1832867 blocks
[12:44:59] scan: checking audio 1
[12:44:59] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[12:44:59] scan: checking audio 2
[12:44:59] scan: id=0x81bd, lang=Unknown (AC3), 3cc=und ext=0
[12:44:59] scan: checking audio 3
[12:44:59] scan: audio channel is not active
[12:44:59] scan: checking subtitle 1
[12:44:59] scan: id=0x21bd, lang=English, 3cc=eng ext=0
[12:44:59] scan: checking subtitle 2
[12:44:59] scan: id=0x23bd, lang=???????, 3cc=ara ext=0
[12:44:59] scan: checking subtitle 3
[12:44:59] scan: id=0x25bd, lang=polski, 3cc=pol ext=0
[12:44:59] scan: checking subtitle 4
[12:44:59] scan: id=0x27bd, lang=????????, 3cc=ell ext=0
[12:44:59] scan: checking subtitle 5
[12:44:59] scan: id=0x29bd, lang=ceština, 3cc=ces ext=0
[12:44:59] scan: checking subtitle 6
[12:44:59] scan: id=0x2bbd, lang=Türkçe, 3cc=tur ext=0
[12:44:59] scan: checking subtitle 7
[12:44:59] scan: id=0x2dbd, lang=magyar, 3cc=hun ext=0
[12:44:59] scan: checking subtitle 8
[12:44:59] scan: id=0x2fbd, lang=íslenska, 3cc=isl ext=0
[12:44:59] scan: checking subtitle 9
[12:44:59] scan: id=0x31bd, lang=hrvatski, 3cc=hrv ext=0
[12:44:59] scan: checking subtitle 10
[12:44:59] scan: id=0x33bd, lang=English, 3cc=eng ext=1
[12:44:59] scan: title 1 has 38 chapters
[12:44:59] scan: chap 1 c=0->0, b=0->22303 (22304), 80960 ms
[12:44:59] scan: chap 2 c=1->1, b=22304->65321 (43018), 150560 ms
[12:44:59] scan: chap 3 c=2->2, b=65322->107786 (42465), 152840 ms
[12:44:59] scan: chap 4 c=3->3, b=107787->153745 (45959), 151880 ms
[12:44:59] scan: chap 5 c=4->4, b=153746->210325 (56580), 192240 ms
[12:44:59] scan: chap 6 c=5->5, b=210326->294736 (84411), 279840 ms
[12:44:59] scan: chap 7 c=6->6, b=294737->356671 (61935), 201720 ms
[12:44:59] scan: chap 8 c=7->7, b=356672->407386 (50715), 175800 ms
[12:44:59] scan: chap 9 c=8->8, b=407387->453477 (46091), 150440 ms
[12:44:59] scan: chap 10 c=9->9, b=453478->468283 (14806), 49000 ms
[12:44:59] scan: chap 11 c=10->10, b=468284->492087 (23804), 83400 ms
[12:44:59] scan: chap 12 c=11->11, b=492088->606953 (114866), 369000 ms
[12:44:59] scan: chap 13 c=12->12, b=606954->763368 (156415), 460480 ms
[12:44:59] scan: chap 14 c=13->13, b=763369->791724 (28356), 102840 ms
[12:44:59] scan: chap 15 c=14->14, b=791725->813581 (21857), 81040 ms
[12:44:59] scan: chap 16 c=15->15, b=813582->833528 (19947), 71960 ms
[12:44:59] scan: chap 17 c=16->16, b=833529->896897 (63369), 222680 ms
[12:44:59] scan: chap 18 c=17->17, b=896898->965077 (68180), 210480 ms
[12:44:59] scan: chap 19 c=18->18, b=965078->1004786 (39709), 136560 ms
[12:44:59] scan: chap 20 c=19->19, b=1004787->1016956 (12170), 42320 ms
[12:44:59] scan: chap 21 c=20->20, b=1016957->1036612 (19656), 71560 ms
[12:44:59] scan: chap 22 c=21->21, b=1036613->1111744 (75132), 275200 ms
[12:44:59] scan: chap 23 c=22->22, b=1111745->1159760 (48016), 169760 ms
[12:44:59] scan: chap 24 c=23->23, b=1159761->1225607 (65847), 201760 ms
[12:44:59] scan: chap 25 c=24->24, b=1225608->1253565 (27958), 90480 ms
[12:44:59] scan: chap 26 c=25->25, b=1253566->1310094 (56529), 162520 ms
[12:44:59] scan: chap 27 c=26->26, b=1310095->1357403 (47309), 133960 ms
[12:44:59] scan: chap 28 c=27->27, b=1357404->1388457 (31054), 109000 ms
[12:44:59] scan: chap 29 c=28->28, b=1388458->1413854 (25397), 78440 ms
[12:44:59] scan: chap 30 c=29->29, b=1413855->1459806 (45952), 151400 ms
[12:44:59] scan: chap 31 c=30->30, b=1459807->1489422 (29616), 104480 ms
[12:44:59] scan: chap 32 c=31->31, b=1489423->1519108 (29686), 100120 ms
[12:44:59] scan: chap 33 c=32->32, b=1519109->1570684 (51576), 180120 ms
[12:44:59] scan: chap 34 c=33->33, b=1570685->1581198 (10514), 31320 ms
[12:44:59] scan: chap 35 c=34->34, b=1581199->1633087 (51889), 181000 ms
[12:44:59] scan: chap 36 c=35->35, b=1633088->1669270 (36183), 113720 ms
[12:44:59] scan: chap 37 c=36->36, b=1669271->1741461 (72191), 235880 ms
[12:44:59] scan: chap 38 c=37->37, b=1741462->1832866 (91405), 331840 ms
[12:44:59] scan: aspect = 16:9
[12:44:59] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[12:44:59] scan: title angle(s) 1
[ac3 @ 000000003a60fcc0] frame sync error
[12:44:59] scan: audio 0x81bd: ac3, rate=48000Hz, bitrate=384000 Unknown (AC3) (5.1 ch)
[ac3 @ 000000003992f4c0] frame sync error
[12:44:59] scan: audio 0x80bd: ac3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[12:44:59] scan: 10 previews, 720x576, 25.000 fps, autocrop = 82/64/8/4, aspect 16:9, PAR 64:45
[12:44:59] libhb: scan thread found 1 valid title(s)
[12:44:59] starting job
[12:44:59] job configuration:
[12:44:59]  * source
[12:44:59]    + E:\DVD ISO BACKUPS\Movies\PAL1.ISO
[12:44:59]    + title 1, chapter(s) 1 to 38
[12:44:59]  * destination
[12:44:59]    + E:\DVD ISO BACKUPS\Movies\PAL1.mkv
[12:44:59]    + container: Matroska (libavformat)
[12:44:59]      + chapter markers
[12:44:59]  * video track
[12:44:59]    + decoder: mpeg2video
[12:44:59]      + bitrate 7500 kbps
[12:44:59]    + filters
[12:44:59]      + Detelecine (pullup) ()
[12:44:59]      + Framerate Shaper (mode=1)
[12:44:59]        + frame rate: 25.000 fps -> constant 25.000 fps
[12:44:59]      + Crop and Scale (width=720:height=576:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[12:44:59]        + source: 720 * 576, crop (0/0/0/0): 720 * 576, scale: 720 * 576
[12:44:59]    + Output geometry
[12:44:59]      + storage dimensions: 720 x 576
[12:44:59]      + pixel aspect ratio: 64 : 45
[12:44:59]      + display dimensions: 1024 x 576
[12:44:59]  * Foreign Audio Search: Passthrough, Forced Only
[12:44:59]    + subtitle, English (track 0, id 0x21bd) Picture [VOBSUB]
[12:44:59]    + subtitle, English (track 9, id 0x33bd) Picture [VOBSUB]
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PAL1
libdvdnav: DVD Serial Number: 2466054B
libdvdnav: DVD Title (Alternative): PAL1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[12:44:59] sync: expecting 152215 video frames
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ
[12:45:37] reader: done. 1 scr changes
[12:45:37] work: average encoding speed for job is 0.000000 fps
[12:45:37] vfr: 0 frames output, 0 dropped and 0 duped for CFR/PFR
[12:45:37] vfr: lost time: 0 (0 frames)
[12:45:37] vfr: gained time: 0 (0 frames) (0 not accounted for)
[12:45:37] sync: got 0 frames, 152215 expected
[12:45:37] Subtitle track 0 (id 0x21bd) 'English': 1085 hits (0 forced)
[12:45:37] Subtitle track 9 (id 0x33bd) 'English': 1364 hits (0 forced)
[12:45:37] No candidate detected during subtitle scan
[12:45:37] starting job
[12:45:37] Auto Passthru: allowed codecs are AAC, AC3, E-AC3, TrueHD, DTS, DTS-HD, MP3, FLAC
[12:45:37] Auto Passthru: fallback is AC3
[12:45:37] Auto Passthru: using AC3 Passthru for track 1
[12:45:37] Auto Passthru: using AC3 Passthru for track 2
[12:45:37] job configuration:
[12:45:37]  * source
[12:45:37]    + E:\DVD ISO BACKUPS\Movies\PAL1.ISO
[12:45:37]    + title 1, chapter(s) 1 to 38
[12:45:37]  * destination
[12:45:37]    + E:\DVD ISO BACKUPS\Movies\PAL1.mkv
[12:45:37]    + container: Matroska (libavformat)
[12:45:37]      + chapter markers
[12:45:37]  * video track
[12:45:37]    + decoder: mpeg2video
[12:45:37]      + bitrate 7500 kbps
[12:45:37]    + filters
[12:45:37]      + Detelecine (pullup) ()
[12:45:37]      + Framerate Shaper (mode=1)
[12:45:37]        + frame rate: 25.000 fps -> constant 25.000 fps
[12:45:37]      + Crop and Scale (width=720:height=576:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[12:45:37]        + source: 720 * 576, crop (0/0/0/0): 720 * 576, scale: 720 * 576
[12:45:37]    + Output geometry
[12:45:37]      + storage dimensions: 720 x 576
[12:45:37]      + pixel aspect ratio: 64 : 45
[12:45:37]      + display dimensions: 1024 x 576
[12:45:37]    + encoder: H.264 (libx264)
[12:45:37]      + preset:  slower
[12:45:37]      + tune:    film
[12:45:37]      + profile: high
[12:45:37]      + level:   4.0
[12:45:37]      + quality: 10.00 (RF)
[12:45:37]  * subtitle track 1, English (track 0, id 0x21bd) Picture [VOBSUB] -> Passthrough
[12:45:37]  * subtitle track 2, English (track 9, id 0x33bd) Picture [VOBSUB] -> Passthrough
[12:45:37]  * audio track 1
[12:45:37]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[12:45:37]      + bitrate: 384 kbps, samplerate: 48000 Hz
[12:45:37]    + AC3 Passthru
[12:45:37]  * audio track 2
[12:45:37]    + decoder: Unknown (AC3) (5.1 ch) (track 2, id 0x81bd)
[12:45:37]      + bitrate: 384 kbps, samplerate: 48000 Hz
[12:45:37]    + AC3 Passthru
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD Title: PAL1
libdvdnav: DVD Serial Number: 2466054B
libdvdnav: DVD Title (Alternative): PAL1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[12:45:37] sync: expecting 152215 video frames
[12:45:37] encx264: encoding at constant RF 10.000000
[12:45:37] encx264: unparsed options: me=umh:level=4.0:ref=8:b-adapt=2:direct=auto:deblock=-1,-1:analyse=all:subme=9:rc-lookahead=60:trellis=2:psy-rd=1,0.15:vbv-bufsize=31250:vbv-maxrate=25000
x264 [info]: using SAR=64/45
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2
x264 [info]: profile High, level 4.0
[12:45:37] sync: first pts audio 0x80bd is 0
[12:45:37] sync: first pts subtitle 0x21bd is 0
[12:45:37] sync: first pts subtitle 0x33bd is 0
[12:45:37] sync: first pts audio 0x81bd is 2
[12:45:37] sync: first pts video is 9360
[12:45:37] sync: "Chapter 1" (1) at frame 1 time 9360
[12:46:28] sync: "Chapter 2" (2) at frame 2025 time 7295760
[12:48:14] sync: "Chapter 3" (3) at frame 5789 time 20846160
[12:49:51] sync: "Chapter 4" (4) at frame 9610 time 34601760
[12:51:56] sync: "Chapter 5" (5) at frame 13407 time 48270960
[12:54:07] sync: "Chapter 6" (6) at frame 18213 time 65572560
[12:57:32] sync: "Chapter 7" (7) at frame 25209 time 90758160
[12:59:40] sync: "Chapter 8" (8) at frame 30252 time 108912960
[13:01:30] sync: "Chapter 9" (9) at frame 34647 time 124734960
[13:03:36] sync: "Chapter 10" (10) at frame 38408 time 138274560
[13:04:09] sync: "Chapter 11" (11) at frame 39633 time 142684560
[13:05:09] sync: "Chapter 12" (12) at frame 41718 time 150190560
[13:10:14] sync: "Chapter 13" (13) at frame 50943 time 183400560
[13:17:13] sync: "Chapter 14" (14) at frame 62455 time 224843760
[13:18:27] sync: "Chapter 15" (15) at frame 65026 time 234099360
[13:19:28] sync: "Chapter 16" (16) at frame 67052 time 241392960
[13:20:21] sync: "Chapter 17" (17) at frame 68851 time 247869360
[13:23:06] sync: "Chapter 18" (18) at frame 74418 time 267910560
[13:25:56] sync: "Chapter 19" (19) at frame 79680 time 286853760
[13:27:31] sync: "Chapter 20" (20) at frame 83094 time 299144160
[13:27:58] sync: "Chapter 21" (21) at frame 84152 time 302952960
[13:28:45] sync: "Chapter 22" (22) at frame 85941 time 309393360
[13:32:11] sync: "Chapter 23" (23) at frame 92821 time 334161360
[13:34:20] sync: "Chapter 24" (24) at frame 97065 time 349439760
[13:37:14] sync: "Chapter 25" (25) at frame 102109 time 367598160
[13:38:25] sync: "Chapter 26" (26) at frame 104371 time 375741360
[13:40:51] sync: "Chapter 27" (27) at frame 108434 time 390368160
[13:42:51] sync: "Chapter 28" (28) at frame 111783 time 402424560
[13:44:14] sync: "Chapter 29" (29) at frame 114508 time 412234560
[13:45:33] sync: "Chapter 30" (30) at frame 116469 time 419294160
[13:47:34] sync: "Chapter 31" (31) at frame 120254 time 432920160
[13:49:06] sync: "Chapter 32" (32) at frame 122866 time 442323360
[13:50:23] sync: "Chapter 33" (33) at frame 125369 time 451334160
[13:52:44] sync: "Chapter 34" (34) at frame 129872 time 467544960
[13:53:14] sync: "Chapter 35" (35) at frame 130655 time 470363760
[13:55:46] sync: "Chapter 36" (36) at frame 135180 time 486653760
[13:57:32] sync: "Chapter 37" (37) at frame 138023 time 496888560
[14:00:36] sync: "Chapter 38" (38) at frame 143920 time 518117760
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ
[14:02:32] reader: done. 1 scr changes
[14:02:33] work: average encoding speed for job is 32.971085 fps
[14:02:34] vfr: 152214 frames output, 0 dropped and 29 duped for CFR/PFR
[14:02:34] vfr: lost time: 97200 (0 frames)
[14:02:34] vfr: gained time: 97200 (108 frames) (0 not accounted for)
[14:02:34] ac3-decoder done: 190269 frames, 0 decoder errors
[14:02:34] ac3-decoder done: 190269 frames, 0 decoder errors
[14:02:34] mpeg2video-decoder done: 152214 frames, 0 decoder errors
[14:02:34] sync: got 152212 frames, 152215 expected
[14:02:34] sync: framerate min 25.000 fps, max 25.000 fps, avg 25.000 fps
x264 [info]: frame I:1654  Avg QP: 6.08  size:113015
x264 [info]: frame P:48510 Avg QP: 8.97  size: 59311
x264 [info]: frame B:102050 Avg QP:12.02  size: 24400
x264 [info]: consecutive B-frames:  2.1%  4.8% 61.8% 31.2%
x264 [info]: mb I  I16..4: 33.0% 51.1% 15.8%
x264 [info]: mb P  I16..4:  5.5% 17.1%  4.8%  P16..4: 18.9% 11.5% 17.6%  1.5%  1.8%    skip:21.2%
x264 [info]: mb B  I16..4:  2.0%  6.6%  1.1%  B16..8: 32.1% 13.3%  6.4%  direct: 9.9%  skip:28.6%  L0:33.9% L1:27.1% BI:39.0%
x264 [info]: 8x8 transform intra:63.9% inter:52.4%
x264 [info]: direct mvs  spatial:96.5% temporal:3.5%
x264 [info]: coded y,uvDC,uvAC intra: 90.3% 93.3% 90.7% inter: 42.9% 42.4% 31.6%
x264 [info]: i16 v,h,dc,p: 23% 20% 39% 18%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 23% 19% 34%  3%  3%  4%  3%  5%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 22% 14% 15%  7%  7% 10%  7%  9%  9%
x264 [info]: i8c dc,h,v,p: 57% 15% 16% 11%
x264 [info]: Weighted P-Frames: Y:3.1% UV:1.5%
x264 [info]: ref P L0: 56.0% 12.4% 14.8%  4.3%  4.4%  2.6%  3.2%  2.2%  0.1%  0.0%
x264 [info]: ref B L0: 73.7% 16.7%  4.2%  2.2%  1.5%  1.2%  0.5%
x264 [info]: ref B L1: 97.5%  2.5%
x264 [info]: kb/s:7297.82
[14:02:34] mux: track 0, 152214 frames, 5554082377 bytes, 7297.63 kbps, fifo 512
[14:02:34] mux: track 1, 190269 frames, 292253184 bytes, 384.00 kbps, fifo 1024
[14:02:34] mux: track 2, 190269 frames, 292253184 bytes, 384.00 kbps, fifo 1024
[14:02:34] mux: track 3, 1085 frames, 1713696 bytes, 2.25 kbps, fifo 16
[14:02:34] mux: track 4, 1364 frames, 2179516 bytes, 2.86 kbps, fifo 16
[14:02:34] libhb: work result = 0

# Encode Completed ...

[\code]