Output video shows longer run time than input video

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
RidleyRumpus
New User
Posts: 1
Joined: Fri Dec 31, 2021 7:05 pm

Output video shows longer run time than input video

Post by RidleyRumpus »

I am trying to convert some old .ty 4:3 interlaced files from a TIVO machine and although Handbrake transcribes the files seemingly OK the output files shows a longer runtime than the input file.

For instance "After Rome.ty is listed as 1hr 10min but the converted file outputted by HANDBRAKE is 2hr 17min. The file plays OK until it gets to the natural end and then the player fails as it is trying to play a position in the file that does not exist. Fast forwarding is also an issue.

Any idea what is going on here?

Could it be something odd with the framerate and interlacing? ie the original is 25fps and interlaced, so 50 fields. Is HandBrake somehow seeing the fields and using that as frames?





Steps to reproduce the problem (If Applicable):




1.4.2 (2021100300):




Windows 10:




HandBrake 1.4.2 (2021100300)
OS: Microsoft Windows NT 10.0.19043.0
CPU: Intel(R) Core(TM) i3-6100 CPU @ 3.70GHz
Ram: 32723 MB,
GPU Information:
Radeon (TM) RX 470 Graphics - 26.20.12028.2
Screen: 1920x1080
Temp Dir: C:\Users\Jenny\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake\
Data Dir: C:\Users\Jenny\AppData\Roaming\HandBrake

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


# Starting Encode ...
[17:56:33] base preset: Fast 1080p30 (Modified)
[17:56:33] hb_init: starting libhb thread
[17:56:33] Starting work at: Fri Dec 31 17:56:33 2021
[17:56:33] 1 job(s) to process
[17:56:33] json job:
{
"Audio": {
"AudioList": [
{
"Bitrate": 160,
"DRC": 0,
"Encoder": "av_aac",
"Gain": 0,
"Mixdown": 4,
"NormalizeMixLevel": false,
"Samplerate": 0,
"Track": 0,
"DitherMethod": 0
}
],
"CopyMask": [
"copy:aac",
"copy:ac3",
"copy:dtshd",
"copy:dts",
"copy:eac3",
"copy:flac",
"copy:mp3",
"copy:truehd",
"copy:mp2"
],
"FallbackEncoder": "ac3"
},
"Destination": {
"ChapterList": [
{
"Name": "Chapter 1"
}
],
"ChapterMarkers": true,
"AlignAVStart": false,
"File": "\\\\TOWER\\Media\\Store\\TIVO Converted\\After Rome- Holy War And Conquest- (1 Of 2)-3.mkv",
"Mp4Options": {
"IpodAtom": false,
"Mp4Optimize": false
},
"Mux": "av_mkv"
},
"Filters": {
"FilterList": [
{
"ID": 4,
"Settings": {
"mode": "7"
}
},
{
"ID": 3,
"Settings": {
"block-height": "16",
"block-thresh": "40",
"block-width": "16",
"filter-mode": "2",
"mode": "3",
"motion-thresh": "1",
"spatial-metric": "2",
"spatial-thresh": "1"
}
},
{
"ID": 13,
"Settings": {
"crop-bottom": "78",
"crop-left": "2",
"crop-right": "2",
"crop-top": "68",
"height": "430",
"width": "348"
}
},
{
"ID": 6,
"Settings": {
"mode": "2",
"rate": "27000000/900000"
}
}
]
},
"PAR": {
"Num": 24,
"Den": 11
},
"Metadata": {},
"SequenceID": 0,
"Source": {
"Angle": 1,
"Range": {
"Type": "chapter",
"Start": 1,
"End": 1
},
"Title": 3,
"Path": "\\\\TOWER\\Media\\Store\\TIVO DL\\After Rome- Holy War and Conquest- (1 of 2).ty"
},
"Subtitle": {
"Search": {
"Burn": true,
"Default": false,
"Enable": true,
"Forced": true
},
"SubtitleList": []
},
"Video": {
"Encoder": "x264",
"Level": "4.0",
"TwoPass": false,
"Turbo": false,
"ColorMatrixCode": 0,
"Options": "",
"Preset": "fast",
"Profile": "main",
"Quality": 22,
"QSV": {
"Decode": false
}
}
}
[17:56:33] CPU: Intel(R) Core(TM) i3-6100 CPU @ 3.70GHz
[17:56:33] - Intel microarchitecture Skylake
[17:56:33] - logical processor count: 4
[17:56:33] Intel Quick Sync Video support: no
[17:56:33] hb_scan: path=\\TOWER\Media\Store\TIVO DL\After Rome- Holy War and Conquest- (1 of 2).ty, title_index=3
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:333: failed opening UDF image \\TOWER\Media\Store\TIVO DL\After Rome- Holy War and Conquest- (1 of 2).ty
src/libbluray/disc/disc.c:437: error opening file BDMV\index.bdmv
src/libbluray/disc/disc.c:437: error opening file BDMV\BACKUP\index.bdmv
src/libbluray/bluray.c:2646: nav_get_title_list(\\TOWER\Media\Store\TIVO DL\After Rome- Holy War and Conquest- (1 of 2).ty\) failed
[17:56:33] bd: not a bd - trying as a stream/file instead
libdvdread: DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav: vm: vm: failed to read VIDEO_TS.IFO
[17:56:33] dvd: not a dvd - trying as a stream/file instead
Input #0, ty, from '\\TOWER\Media\Store\TIVO DL\After Rome- Holy War and Conquest- (1 of 2).ty':
Duration: 01:10:45.52, start: 1.083822, bitrate: 2791 kb/s
Stream #0:0: Video: mpeg2video (Main), yuv420p(tv, top first), 352x576 [SAR 24:11 DAR 4:3], 2600 kb/s, 25 fps, 25 tbr, 90k tbn, 50 tbc
Side data:
cpb: bitrate max/min/avg: 2600000/0/0 buffer size: 1835008 vbv_delay: N/A
Stream #0:1: Audio: mp2, 32000 Hz, stereo, s16p, 192 kb/s
[17:56:33] scan: decoding previews for title 3
[17:56:33] scan: audio 0x1: mp2, rate=32000Hz, bitrate=192000 Unknown (MPEG) (2.0 ch) (192 kbps)
avformat_seek_file failed
Last error repeated 8 times
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
avformat_seek_file failed
[17:56:53] scan: 10 previews, 352x576, 25.000 fps, autocrop = 68/78/2/2, aspect 4:3, PAR 24:11, color profile: 5-1-6
[17:56:53] libhb: scan thread found 1 valid title(s)
[17:56:53] Skipping subtitle scan. No suitable subtitle tracks.
[17:56:53] Starting Task: Encoding Pass
[17:56:53] work: track 1, dithering not supported by codec
[17:56:53] work: only 1 chapter, disabling chapter markers
[17:56:53] job configuration:
[17:56:53] * source
[17:56:53] + \\TOWER\Media\Store\TIVO DL\After Rome- Holy War and Conquest- (1 of 2).ty
[17:56:53] + title 3, chapter(s) 1 to 1
[17:56:53] + container: ty
[17:56:53] + data rate: 2791 kbps
[17:56:53] * destination
[17:56:53] + \\TOWER\Media\Store\TIVO Converted\After Rome- Holy War And Conquest- (1 Of 2)-3.mkv
[17:56:53] + container: Matroska (libavformat)
[17:56:53] * video track
[17:56:53] + decoder: mpeg2video 8-bit (yuv420p)
[17:56:53] + bitrate 2600 kbps
[17:56:53] + filters
[17:56: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:56:53] + Decomb (mode=39)
[17:56:53] + Framerate Shaper (mode=2:rate=27000000/900000)
[17:56:53] + frame rate: 25.000 fps -> peak rate limited to 30.000 fps
[17:56:53] + Crop and Scale (width=348:height=430:crop-top=68:crop-bottom=78:crop-left=2:crop-right=2)
[17:56:53] + source: 352 * 576, crop (68/78/2/2): 348 * 430, scale: 348 * 430
[17:56:53] + Output geometry
[17:56:53] + storage dimensions: 348 x 430
[17:56:53] + pixel aspect ratio: 24 : 11
[17:56:53] + display dimensions: 759 x 430
[17:56:53] + encoder: H.264 (libx264)
[17:56:53] + preset: fast
[17:56:53] + profile: main
[17:56:53] + level: 4.0
[17:56:53] + quality: 22.00 (RF)
[17:56:53] + color profile: 5-1-6
[17:56:53] * audio track 1
[17:56:53] + decoder: Unknown (MPEG) (2.0 ch) (192 kbps) (track 1, id 0x1)
[17:56:53] + bitrate: 192 kbps, samplerate: 32000 Hz
[17:56:53] + mixdown: Stereo
[17:56:53] + encoder: AAC (libavcodec)
[17:56:53] + bitrate: 160 kbps, samplerate: 32000 Hz
[17:56:53] sync: expecting 106138 video frames
[17:56:53] encx264: encoding at constant RF 22.000000
[17:56:53] encx264: unparsed options: level=4.0:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=25000:vbv-maxrate=20000:rc-lookahead=30
x264 [info]: using SAR=24/11
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x264 [info]: profile Main, level 4.0, 4:2:0, 8-bit
[17:56:53] sync: first pts audio 0x1 is 0
[17:56:53] sync: first pts video is 12833
[17:56:53] sync: "Chapter 1" (1) at frame 1 time 12833
[17:56:58] sync: video time went backwards 40 ms, dropped 1 frames. PTS 2374433
[18:02:52] sync: video time went backwards 40 ms, dropped 1 frames. PTS 185542058
[18:03:03] sync: video time went backwards 40 ms, dropped 1 frames. PTS 191723208
[18:03:10] sync: audio 0x1 time went backwards 180 ms, dropped 5 frames. PTS 194869800
[18:03:10] sync: video time went backwards 80 ms, dropped 2 frames. PTS 194882350
[18:04:40] sync: video time went backwards 40 ms, dropped 1 frames. PTS 240851520
[18:06:28] sync: video time went backwards 40 ms, dropped 1 frames. PTS 297069118
[18:07:48] sync: video time went backwards 40 ms, dropped 1 frames. PTS 331085230
[18:09:21] reader: done. 1 scr changes
[18:09:21] work: average encoding speed for job is 140.436432 fps
[18:09:21] comb detect: heavy 16266 | light 13767 | uncombed 75003 | total 105036
[18:09:22] decomb: deinterlaced 16266 | blended 13767 | unfiltered 75003 | total 105036
[18:09:22] vfr: 105036 frames output, 0 dropped and 0 duped for CFR/PFR
[18:09:22] vfr: lost time: 0 (0 frames)
[18:09:22] vfr: gained time: 0 (0 frames) (0 not accounted for)
[18:09:22] mp2-decoder done: 116711 frames, 0 decoder errors
[18:09:22] mpeg2video-decoder done: 105045 frames, 0 decoder errors
[18:09:22] sync: got 105036 frames, 106138 expected
[18:09:22] sync: framerate min 0.000 fps, max 208.817 fps, avg 12.778 fps
x264 [info]: frame I:815 Avg QP:20.54 size: 30084
x264 [info]: frame P:28244 Avg QP:23.27 size: 6364
x264 [info]: frame B:75977 Avg QP:27.31 size: 1036
x264 [info]: consecutive B-frames: 1.8% 3.4% 5.7% 89.1%
x264 [info]: mb I I16..4: 12.3% 0.0% 87.7%
x264 [info]: mb P I16..4: 1.1% 0.0% 4.3% P16..4: 46.5% 21.4% 15.0% 0.0% 0.0% skip:11.8%
x264 [info]: mb B I16..4: 0.9% 0.0% 0.6% B16..8: 16.1% 5.4% 0.7% direct:15.7% skip:60.6% L0:31.9% L1:45.6% BI:22.5%
x264 [info]: coded y,uvDC,uvAC intra: 71.5% 83.6% 54.2% inter: 17.2% 32.6% 3.8%
x264 [info]: i16 v,h,dc,p: 46% 21% 15% 18%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 37% 12% 12% 5% 6% 10% 4% 9% 5%
x264 [info]: i8c dc,h,v,p: 41% 14% 36% 9%
x264 [info]: Weighted P-Frames: Y:3.4% UV:2.0%
x264 [info]: ref P L0: 66.0% 34.0%
x264 [info]: ref B L0: 86.1% 13.9%
x264 [info]: ref B L1: 95.3% 4.7%
x264 [info]: kb/s:275.43
[18:09:22] mux: track 0, 105036 frames, 282973227 bytes, 275.39 kbps, fifo 4096
[18:09:22] mux: track 1, 131295 frames, 84498865 bytes, 82.23 kbps, fifo 8192
[18:09:22] Finished work at: Fri Dec 31 18:09:22 2021
[18:09:22] libhb: work result = 0

# Job Completed!
(see How-to get an activity log)

Code: Select all

Please replace this text with the contents of your log file between the two code tags - OR -  provide a pastebin URL in place of these 3 lines.
If you are unable to do so, please state why so we can help you.
Post Reply