[Fixed 4352] Existing subtitle cause duration of "13:15:21"

[marcbau]

I figured out with a few videos that the subtitles are causing a duration of 13 hours, 15 minutes and 21 seconds. This is reproducible with all these videos. It happens with ~30% of all my videos . I tried all codecs like x264, MPEG4 and MPEG2, with AC3 track pass-trough only and with one AAC track only and after very many tries the subtitles have been identified as the source of this issue.

Windows 7, x64, Handbrake x86, Preset "High Profile", Very many DVDs...

What can be the reason? Is there any other workaround available - aside of removing all subtitles?

Code: Select all

HandBrake svn3972 2011050201
OS: Microsoft Windows NT 6.1.7600.0
CPU: Intel(R) Core(TM)2 Quad CPU    Q9650  @ 3.00GHz
Ram: 3965 MB, Screen: 1920x1200
Temp Dir: C:\Users\myuser\AppData\Local\Temp\
Install Dir: C:\Program Files (x86)\Handbrake
Data Dir: C:\Users\myuser\AppData\Roaming\HandBrake\HandBrake\0.9.5.3972

CLI Query:  -i "C:\Temp\myvideo" -t 1 -c 1-17 -o "D:\Videos\myvideo.m4v"  -f mp4 --detelecine --decomb -w 720 --loose-anamorphic  -e x264 -q 20 --vfr  -a 2,2 -E faac,copy:ac3 -B 320,160 -6 dpl2,auto -R Auto,Auto -D 0,0 --gain=0,0 --subtitle 7 --markers="C:\Temp\myvideo.csv" -x b-adapt=2:rc-lookahead=50 --verbose=2
User Query: False
-------------------------------------------

[16:07:49] hb_init: starting libhb thread
[16:07:49] thread 1a2f818 started ("libhb")
HandBrake svn3972 (2011050201) - MinGW i386 - http://handbrake.fr
4 CPUs detected
Opening C:\Temp\myvideo...
[16:07:49] hb_scan: path=C:\Temp\myvideo, title_index=1
[16:07:49] thread 1a2f8f0 started ("scan")
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening C:\Temp\myvideo/BDMV/index.bdmv
libbluray/bluray.c:1376: nav_get_title_list(C:\Temp\myvideo) failed (0x1a30930)
[16:07:49] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[16:07:49] scan: DVD has 9 title(s)
[16:07:49] scan: scanning title 1
[16:07:49] scan: opening IFO for VTS 1
[16:07:49] scan: duration is 02:17:19 (8239010 ms)
[16:07:49] pgc_id: 1, pgn: 1: pgc: 0x1a33078
[16:07:49] scan: vts=1, ttn=1, cells=0->68, blocks=358->178, 3236873 blocks
[16:07:49] scan: checking audio 1
[16:07:49] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[16:07:49] scan: checking audio 2
[16:07:49] scan: id=0x81bd, lang=Deutsch (AC3), 3cc=deu ext=0
[16:07:49] scan: checking audio 3
[16:07:49] scan: id=0x82bd, lang=Italiano (AC3), 3cc=ita ext=0
[16:07:49] scan: checking subtitle 1
[16:07:49] scan: id=0x20bd, lang=English, 3cc=eng
[16:07:49] scan: checking subtitle 2
[16:07:49] scan: id=0x21bd, lang=English (Closed Caption), 3cc=eng
[16:07:49] scan: checking subtitle 3
[16:07:49] scan: id=0x22bd, lang=Deutsch, 3cc=deu
[16:07:49] scan: checking subtitle 4
[16:07:49] scan: id=0x23bd, lang=Italiano, 3cc=ita
[16:07:49] scan: checking subtitle 5
[16:07:49] scan: id=0x24bd, lang=Francais, 3cc=fra
[16:07:49] scan: checking subtitle 6
[16:07:49] scan: id=0x25bd, lang=Espanol, 3cc=spa
[16:07:49] scan: checking subtitle 7
[16:07:49] scan: id=0x26bd, lang=Deutsch (Forced Caption), 3cc=deu
[16:07:49] scan: checking subtitle 8
[16:07:49] scan: id=0x27bd, lang=Italiano (Forced Caption), 3cc=ita
[16:07:49] scan: title 1 has 17 chapters
[16:07:49] scan: chap 1 c=0->5, b=358->181836 (181479), 511022 ms
[16:07:49] scan: chap 2 c=6->11, b=181837->363526 (181690), 416394 ms
[16:07:49] scan: chap 3 c=12->17, b=363527->626928 (263402), 643765 ms
[16:07:49] scan: chap 4 c=18->19, b=626929->827172 (200244), 502390 ms
[16:07:49] scan: chap 5 c=20->22, b=827173->930944 (103772), 290017 ms
[16:07:49] scan: chap 6 c=23->24, b=930945->1112099 (181155), 419352 ms
[16:07:49] scan: chap 7 c=25->30, b=1112100->1247040 (134941), 326829 ms
[16:07:49] scan: chap 8 c=31->37, b=1247041->1478818 (231778), 578541 ms
[16:07:49] scan: chap 9 c=38->41, b=1478819->1726052 (247234), 682151 ms
[16:07:49] scan: chap 10 c=42->45, b=1726053->1831372 (105320), 310148 ms
[16:07:49] scan: chap 11 c=46->51, b=1831373->2165653 (334281), 785219 ms
[16:07:49] scan: chap 12 c=52->52, b=2165654->2286215 (120562), 289005 ms
[16:07:49] scan: chap 13 c=53->55, b=2286216->2454960 (168745), 474540 ms
[16:07:49] scan: chap 14 c=56->61, b=2454961->2763051 (308091), 793159 ms
[16:07:49] scan: chap 15 c=62->65, b=2763052->3016737 (253686), 640236 ms
[16:07:49] scan: chap 16 c=66->67, b=3016738->3237051 (220314), 576145 ms
[16:07:49] scan: chap 17 c=68->68, b=0->178 (179), 90 ms
[16:07:49] scan: aspect = 0
[16:07:49] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:07:49] scan: title angle(s) 1
[16:07:49] scan: preview 1
[16:07:49] fifo_close: trashing 0 buffer(s)
[16:07:49] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[16:07:49] fifo_close: trashing 0 buffer(s)
[16:07:49] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=384000 Deutsch (AC3) (5.1 ch)
[16:07:49] fifo_close: trashing 0 buffer(s)
[16:07:49] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=384000 Italiano (AC3) (5.1 ch)
[16:07:49] scan: preview 2
[16:07:49] scan: preview 3
[16:07:49] scan: preview 4
[16:07:49] scan: preview 5
[16:07:49] scan: preview 6
[16:07:49] scan: preview 7
[16:07:49] scan: preview 8
[16:07:49] scan: preview 9
[16:07:49] scan: preview 10
[16:07:49] scan: 10 previews, 720x576, 25.000 fps, autocrop = 72/70/0/0, aspect 16:9, PAR 64:45
[16:07:49] scan: title (0) job->width:720, job->height:304
[16:07:49] thread 1a2f8f0 exited ("scan")
Scanning title 1...
[16:07:50] thread 1a2f8f0 joined ("scan")
[16:07:50] libhb: scan thread found 1 valid title(s)
+ title 1:
  + vts 1, ttn 1, cells 0->68 (3236873 blocks)
  + duration: 02:17:19
  + size: 720x576, pixel aspect: 64/45, display aspect: 1.78, 25.000 fps
  + autocrop: 72/70/0/0
  + chapters:
    + 1: cells 0->5, 181479 blocks, duration 00:08:31
    + 2: cells 6->11, 181690 blocks, duration 00:06:56
    + 3: cells 12->17, 263402 blocks, duration 00:10:44
    + 4: cells 18->19, 200244 blocks, duration 00:08:22
    + 5: cells 20->22, 103772 blocks, duration 00:04:50
    + 6: cells 23->24, 181155 blocks, duration 00:06:59
    + 7: cells 25->30, 134941 blocks, duration 00:05:27
    + 8: cells 31->37, 231778 blocks, duration 00:09:39
    + 9: cells 38->41, 247234 blocks, duration 00:11:22
    + 10: cells 42->45, 105320 blocks, duration 00:05:10
    + 11: cells 46->51, 334281 blocks, duration 00:13:05
    + 12: cells 52->52, 120562 blocks, duration 00:04:49
    + 13: cells 53->55, 168745 blocks, duration 00:07:55
    + 14: cells 56->61, 308091 blocks, duration 00:13:13
    + 15: cells 62->65, 253686 blocks, duration 00:10:40
    + 16: cells 66->67, 220314 blocks, duration 00:09:36
    + 17: cells 68->68, 179 blocks, duration 00:00:00
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 448000bps
    + 2, Deutsch (AC3) (5.1 ch) (iso639-2: deu), 48000Hz, 384000bps
    + 3, Italiano (AC3) (5.1 ch) (iso639-2: ita), 48000Hz, 384000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, English (Closed Caption) (iso639-2: eng) (Bitmap)(VOBSUB)
    + 3, Deutsch (iso639-2: deu) (Bitmap)(VOBSUB)
    + 4, Italiano (iso639-2: ita) (Bitmap)(VOBSUB)
    + 5, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 6, Espanol (iso639-2: spa) (Bitmap)(VOBSUB)
    + 7, Deutsch (Forced Caption) (iso639-2: deu) (Bitmap)(VOBSUB)
    + 8, Italiano (Forced Caption) (iso639-2: ita) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Temp\myvideo.csv
[16:07:50] thread 1a2f8f0 started ("work")
[16:07:50] 1 job(s) to process
[16:07:50] starting job
[16:07:50] sync: expecting 206000 video frames
[ac3 @ 0x1a958e0] No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[16:07:50] job configuration:
[16:07:50]  * source
[16:07:50]    + C:\Temp\myvideo
[16:07:50]    + title 1, chapter(s) 1 to 17
[16:07:50]  * destination
[16:07:50]    + D:\Videos\myvideo.m4v
[16:07:50]    + container: MPEG-4 (.mp4 and .m4v)
[16:07:50]      + chapter markers
[16:07:50]  * video track
[16:07:50]    + decoder: mpeg2
[16:07:50]      + bitrate 9800 kbps
[16:07:50]    + frame rate: same as source (around 25.000 fps)
[16:07:50]    + loose anamorphic
[16:07:50]      + storage dimensions: 720 * 576 -> 720 * 432, crop 72/70/0/0, mod 0
[16:07:50]      + pixel aspect ratio: 1536 / 1085
[16:07:50]      + display dimensions: 1019 * 432
[16:07:50]    + filters
[16:07:50]      + Detelecine (pullup) (default settings)
[16:07:50]      + Decomb (default settings)
[16:07:50]    + encoder: x264
[16:07:50]      + options: b-adapt=2:rc-lookahead=50
[16:07:50]      + quality: 20.00 (RF)
[16:07:50]  * subtitle track 7, Deutsch (Forced Caption) (id 0x26bd) Picture [VOBSUB] -> Pass-Through
[16:07:50]  * audio track 0
[16:07:50]    + decoder: Deutsch (AC3) (5.1 ch) (track 2, id 0x81bd)
[16:07:50]      + bitrate: 384 kbps, samplerate: 48000 Hz
[16:07:50]    + mixdown: Dolby Pro Logic II
[16:07:50]    + encoder: faac
[16:07:50]      + bitrate: 320 kbps, samplerate: 48000 Hz
[16:07:50]  * audio track 1
[16:07:50]    + decoder: Deutsch (AC3) (5.1 ch) (track 2, id 0x81bd)
[16:07:50]      + bitrate: 384 kbps, samplerate: 48000 Hz
[16:07:50]    + AC3 passthrough
[16:07:50] thread 1a4a1e0 started ("reader")
libdvdnav: Using dvdnav version 4.1.3
[16:07:50] thread 1a49a08 started ("AudioSynchronization")
[16:07:50] thread 1a36210 started ("AudioSynchronization")
[16:07:50] thread 1a362a8 started ("MPEG-2 decoder (libmpeg2)")
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[16:07:50] thread 272f5d0 started ("yadif_filter_segment")
[16:07:50] thread 272f888 started ("yadif_filter_segment")
[16:07:50] thread 272fd48 started ("yadif_filter_segment")
[16:07:50] thread 272fde0 started ("yadif_filter_segment")
[16:07:50] thread 272fe78 started ("decomb_filter_segment")
[16:07:50] yadif thread started for segment 2
[16:07:50] yadif thread started for segment 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3[16:07:50] decomb thread started for segment 1
 4 5 6[16:07:50] decomb thread started for segment 0
 7 8
[16:07:50] thread 272f920 started ("decomb_filter_segment")
[16:07:50] yadif thread started for segment 0
[16:07:50] yadif thread started for segment 3
[16:07:50] thread 1affee0 started ("decomb_filter_segment")
[16:07:50] decomb thread started for segment 2
[16:07:50] thread 272f9b8 started ("decomb_filter_segment")
[16:07:50] thread 272fa50 started ("Renderer")
[16:07:50] encx264: encoding with stored aspect 1536/1085
[16:07:50] encx264: Encoding at constant RF 20.000000
[16:07:50] encx264: opening libx264 (pass 0)
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=1536/1085
[16:07:50] decomb thread started for segment 3
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.1 Cache64
[16:07:50] reader: first SCR 146 id 0xe0 DTS 22254
[16:07:50] mpeg2: "Chapter 1" (1) at frame 0 time 3600
x264 [info]: profile High, level 3.0
[16:07:50] thread 3580720 started ("H.264/AVC encoder (libx264)")
[16:07:50] thread 35807b8 started ("VOBSUB decoder")
No accelerated IMDCT transform found
[16:07:50] thread 3580850 started ("AC3 decoder")
[16:07:50] thread 35808e8 started ("AAC encoder (libfaac)")
No accelerated IMDCT transform found
[16:07:50] thread 3580980 started ("AC3 decoder")
[16:07:50] thread 3580a18 started ("Video Synchronization")
[16:07:50] muxmp4: track 0, chunk duration 14400
[16:07:50] muxmp4: track 1, chunk duration 7680
[16:07:50] muxmp4: track 2, chunk duration 7680
[16:07:50] muxmp4: disabled extra audio track 2
[16:07:50] muxmp4: track 3, chunk duration 14400
[16:07:50] thread 3580ab0 started ("Muxer")
[16:07:50] thread 3580b48 started ("Muxer")
[16:07:50] thread 3580be0 started ("Muxer")
[16:12:09] mpeg2: "Chapter 2" (2) at frame 12763 time 45950400
[16:16:00] mpeg2: "Chapter 3" (3) at frame 23176 time 83437200
[16:21:54] mpeg2: "Chapter 4" (4) at frame 39259 time 141336000
[16:26:20] mpeg2: "Chapter 5" (5) at frame 51799 time 186480879
[16:28:40] mpeg2: "Chapter 6" (6) at frame 59078 time 212685279
[16:32:39] mpeg2: "Chapter 7" (7) at frame 69555 time 250402479
[16:35:58] mpeg2: "Chapter 8" (8) at frame 77756 time 279927016
[16:41:36] mpeg2: "Chapter 9" (9) at frame 92230 time 332033416
[16:48:06] mpeg2: "Chapter 10" (10) at frame 109282 time 393421524
[16:50:52] mpeg2: "Chapter 11" (11) at frame 117063 time 421433124
[16:59:19] mpeg2: "Chapter 12" (12) at frame 136693 time 492101124
[17:02:23] mpeg2: "Chapter 13" (13) at frame 143900 time 518047187
[17:06:35] mpeg2: "Chapter 14" (14) at frame 155765 time 560762078
[17:14:04] mpeg2: "Chapter 15" (15) at frame 175593 time 632142878
[17:19:48] mpeg2: "Chapter 16" (16) at frame 191586 time 689717678
[17:22:55] mpeg2: "Chapter 17" (17) at frame 205973 time 741511781
[17:22:55] 8239.019789s: Video -> Film
[17:22:55] reader: done. 7 scr changes
[17:22:55] thread 1a4a1e0 exited ("reader")
[17:22:56] work: average encoding speed for job is 45.715004 fps
[17:22:56] thread 1a36210 exited ("AudioSynchronization")
[17:22:56] thread 3580850 exited ("AC3 decoder")
[17:22:56] thread 1a362a8 exited ("MPEG-2 decoder (libmpeg2)")
[17:22:56] thread 3580980 exited ("AC3 decoder")
[17:22:56] thread 1a49a08 exited ("AudioSynchronization")
[17:22:56] thread 3580b48 exited ("Muxer")
[17:22:56] thread 35807b8 exited ("VOBSUB decoder")
[17:22:56] thread 35808e8 exited ("AAC encoder (libfaac)")
[17:22:56] thread 3580ab0 exited ("Muxer")
[17:22:56] thread 272fa50 exited ("Renderer")
[17:22:56] thread 3580a18 exited ("Video Synchronization")
[17:22:56] thread 3580be0 exited ("Muxer")
[17:22:56] thread 3580a18 joined ("Video Synchronization")
[17:22:56] sync: got 205985 frames, 206000 expected
[17:22:56] thread 1a49a08 joined ("AudioSynchronization")
[17:22:56] thread 1a36210 joined ("AudioSynchronization")
[17:22:56] thread 1a362a8 joined ("MPEG-2 decoder (libmpeg2)")
[17:22:56] mpeg2 done: 205985 frames
[17:22:56] thread 272fa50 joined ("Renderer")
[17:22:56] render: lost time: 97200 (27 frames)
[17:22:56] render: gained time: 97200 (105 frames) (0 not accounted for)
[17:22:56] render: average dropped frame duration: 3600
[17:22:56] fifo_close: trashing 1 buffer(s)
[17:22:56] fifo_close: trashing 0 buffer(s)
[17:22:56] thread 3580720 exited ("H.264/AVC encoder (libx264)")
[17:22:56] thread 3580720 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:2547  Avg QP:17.51  size: 35861  PSNR Mean Y:46.34 U:50.51 V:51.06 Avg:47.23 Global:46.74
x264 [info]: frame P:61171 Avg QP:20.71  size: 11787  PSNR Mean Y:44.63 U:52.31 V:52.43 Avg:45.67 Global:44.44
x264 [info]: frame B:142239 Avg QP:23.32  size:  4399  PSNR Mean Y:42.81 U:50.21 V:50.55 Avg:43.92 Global:43.30
x264 [info]: consecutive B-frames:  3.1%  5.2% 28.0% 63.8%
x264 [info]: mb I  I16..4:  8.2% 76.8% 15.0%
x264 [info]: mb P  I16..4:  2.1% 12.3%  2.3%  P16..4: 35.9% 19.8% 11.4%  0.0%  0.0%    skip:16.1%
x264 [info]: mb B  I16..4:  0.2%  1.8%  0.5%  B16..8: 43.3%  9.1%  2.3%  direct: 4.0%  skip:38.8%  L0:39.3% L1:47.7% BI:13.0%
x264 [info]: 8x8 transform intra:74.0% inter:76.7%
x264 [info]: coded y,uvDC,uvAC intra: 77.6% 74.9% 38.0% inter: 25.7% 25.5% 1.2%
x264 [info]: i16 v,h,dc,p: 51% 16%  8% 25%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 21% 11% 20%  6%  8% 11%  6% 10%  7%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 24% 11% 14%  7% 10% 13%  6%  9%  5%
x264 [info]: i8c dc,h,v,p: 53% 13% 26%  7%
x264 [info]: Weighted P-Frames: Y:6.4% UV:3.7%
x264 [info]: ref P L0: 55.5% 15.8% 20.2%  8.1%  0.5%
x264 [info]: ref B L0: 87.1% 10.6%  2.4%
x264 [info]: ref B L1: 94.4%  5.6%
x264 [info]: SSIM Mean Y:0.9788617 (16.749db)
x264 [info]: PSNR Mean Y:43.392 U:50.835 V:51.116 Avg:44.484 Global:43.644 kb/s:1396.27
[17:22:56] thread 35807b8 joined ("VOBSUB decoder")
[17:22:56] thread 3580850 joined ("AC3 decoder")
[17:22:56] thread 35808e8 joined ("AAC encoder (libfaac)")
[17:22:56] thread 3580980 joined ("AC3 decoder")
[17:22:56] thread 3580ab0 joined ("Muxer")
[17:22:56] thread 3580b48 joined ("Muxer")
[17:22:56] thread 3580be0 joined ("Muxer")
[17:23:04] mux: file size, 2139233427 bytes
[17:23:04] mux: track 0, 205957 frames, 1437947782 bytes, 1396.16 kbps, fifo 8
[17:23:04] mux: track 1, 386198 frames, 300984176 bytes, 292.24 kbps, fifo 512
[17:23:04] mux: track 2, 257465 frames, 395466240 bytes, 383.97 kbps, fifo 256
[17:23:04] mux: track 3, 8 frames, 11484 bytes, 0.01 kbps, fifo 8
[17:23:04] mux: overhead, 5.68 bytes per frame
[17:23:04] thread 1a4a1e0 joined ("reader")
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] fifo_close: trashing 0 buffer(s)
[17:23:04] decomb: deinterlaced 0 | blended 0 | unfiltered 205957 | total 205957
[17:23:04] thread 272f5d0 exited ("yadif_filter_segment")
[17:23:04] thread 272f5d0 joined ("yadif_filter_segment")
[17:23:04] thread 272f888 exited ("yadif_filter_segment")
[17:23:04] thread 272f888 joined ("yadif_filter_segment")
[17:23:04] thread 272fd48 exited ("yadif_filter_segment")
[17:23:04] thread 272fd48 joined ("yadif_filter_segment")
[17:23:04] thread 272fde0 exited ("yadif_filter_segment")
[17:23:04] thread 272fde0 joined ("yadif_filter_segment")
[17:23:04] thread 272fe78 exited ("decomb_filter_segment")
[17:23:04] thread 272fe78 joined ("decomb_filter_segment")
[17:23:04] thread 272f920 exited ("decomb_filter_segment")
[17:23:04] thread 272f920 joined ("decomb_filter_segment")
[17:23:04] thread 1affee0 exited ("decomb_filter_segment")
[17:23:04] thread 1affee0 joined ("decomb_filter_segment")
[17:23:04] thread 272f9b8 exited ("decomb_filter_segment")
[17:23:04] thread 272f9b8 joined ("decomb_filter_segment")
[17:23:04] Freed 32 buffers of size 1024
[17:23:04] Freed 32 buffers of size 2048
[17:23:04] Freed 1 buffers of size 4096
[17:23:04] Freed 32 buffers of size 16384
[17:23:04] Freed 1 buffers of size 32768
[17:23:04] Freed 2 buffers of size 131072
[17:23:04] Freed 32 buffers of size 524288
[17:23:04] Freed 32 buffers of size 1048576
[17:23:04] Allocated 51253248 bytes of buffers on this pass and Freed 51253248 bytes, 0 bytes leaked
[17:23:04] thread 1a2f8f0 exited ("work")
[17:23:04] thread 1a2f8f0 joined ("work")
[17:23:04] libhb: work result = 0
Rip done!
[17:23:04] thread 1a2f818 exited ("libhb")
[17:23:04] thread 1a2f818 joined ("libhb")
HandBrake has exited.

[Deleted User 11865]

How are you checking the subtitle duration? Aside from this, is there another issue (subtitles out of sync in VLC or MPlayer)?

[Torzak]

I've seen this problem with one movie I tried recently using svn3972 using VLC (1.1.9) to play the file. I don't have a log because I just reformatted my computer yesterday.

There were no sync issues with the file, and to give an example of what would happen in my case:

Double click movie
Opens in VLC
Movie starts playing showing a duration of ~13hrs
Watch movie until movie ends at around 2hrs
From the 2hr time mark to the 13hr time mark, it actually plays by in about 1sec flat.

Basically the movie plays fine with no sync issues audio, subtitle, or otherwise, and the only thing I saw was a reported 13hr duration that had only the effect of messing up the seek slider bar's accuracy; if you wanted to jump to the middle of the movie and dragged the slider bar in VLC to the middle of the movie, you'd be dragging it to about 6/7hrs into the movie in which case you'd get a black picture for about 1sec and then the movie was over.

Whatever was happening I thought was an isolated event, but I haven't been doing much for encodes lately.

If I play the same file in Windows Media Player with Shark's codecs installed, Windows Media Player doesn't choke up on the duration of the file and it plays and reports the duration just fine which leads me to believe that it was just an isolated event, except now I see this thread which makes me wonder.

The event I describe for me may or may not be related because I didn't have it isolated down to the problem being related to subtitles. I could probably redo the movie, testing with and without subtitles using 3972, but I won't have a result very quickly unless I run it through really low res and fast settings specifically to test this theory, and even then it'd probably be a few hours to get a test with subs and another without subs.

Edit: Maybe I'll have something in the hour, it's going faster than I thought it would at these settings.

[marcbau]

There are several ways to check the duration.

1. Turn on the length column in Explorer
2. Open it in any player you'd like. The video goes as long as it is and have ~11 or more hours black screen without any sound on the end.
3. Check the file meta data with MediaInfo tool.

Media Player show the same issue for me. After the film ends, it is black or empty - whatever. Media Player hust stops playing and the bar and video length is shown as 13h

[marcbau]

Only as a note - this does not require latest DEV. 0.9.5 also have this bug.

[Deleted User 11865]

Does it also happen if you encode to MKV instead of MP4?

[Torzak]

I've just finished the encode with subtitles of the same movie that gave me problems before. I went with a container of M4V. Interesting that VLC(1.1.9) reports the duration exactly the same as the OP describes: 13:15:21

On the problem child movie I found that sounds similar to what the OP describes, MediaInfo says 1hr28min and like I said before, Windows Media Player didn't have a problem playing it at all. It doesn't have a problem with reporting it's duration properly. It's just VLC from what I can tell.

I suspect it's possibly a VLC issue, but really, I have no idea.

Should I bother doing the second test encode without subtitles or posting logs?

Just to be clear, I'm not the original poster.

[marcbau]

Does it also happen if you encode to MKV instead of MP4?

Never tried it yet... I can try this night.

[marcbau]

I've just finished the encode with subtitles of the same movie that gave me problems before. I went with a container of M4V. Interesting that VLC(1.1.9) reports the duration exactly the same as the OP describes: 13:15:21

On the problem child movie I found that sounds similar to what the OP describes, MediaInfo says 1hr28min and like I said before, Windows Media Player didn't have a problem playing it at all. It doesn't have a problem with reporting it's duration properly. It's just VLC from what I can tell.

I suspect it's possibly a VLC issue, but really, I have no idea.

Should I bother doing the second test encode without subtitles or posting logs?

Just to be clear, I'm not the original poster.

No, for sure it's not VLC. But I've also seen MediaInfo sometimes show the correct time and sometimes (mostly) the wrong "13:15:21". What is also interested - it doesn't matter what video it is - the duration is always 13:15:21.

Should I bother doing the second test encode without subtitles or posting logs?

I guess the time will be correct then. I have tried with both audio tracks - each alone, both together - no success. The time was only correct after I removed ALL subtitles.

[Torzak]

So mediainfo is sometimes right and sometimes wrong for you depending on your source. That's interesting. So the source I'm using must be one where VLC reports wrong and mediainfo reports right.

I haven't done a whole lot of encoding lately, I just know that this one particular movie I set aside as a sort of, "maybe try this one again later" and remembered that I had done it on 3972. I suppose it's possible that if I thumb through some other sources encoding them at these fast settings I'll find other problems matching to what the OP is talking about, but I suspect there's decent probability that could take a while before I found another assuming the OP's 30% of movies figure.

Anyway, I went ahead and did an MKV test of the same source I had problems with, and quite interestingly, VLC has no problems reporting the correct duration after encoding the source with subtitles.

[Torzak]

I updated to 3975

I've encoded 5 sources now, and on the 5th I found another that's duration appears as 13:15:21. That's not what was interesting to me though. For some reason, the "Video" tab at one point showed a QP 12 instead of RF18.5 I had set. I looked at it puzzled for a second and then I imported another source and the Video tab went back to showing RF 18.5. I thought it was a whole other bug completely unrelated and I wasn't going to mess with it, but maybe it's relevant. Turns out, looking through logs, one time one of my problem videos looks like it encoded as regular asp mp4 instead of avc and I don't know how and nor did I realize it had done so.

I encoded the file again using the same settings, and it seemed like it encoded in AVC the next time.

Both cases it played in VLC showing the 13:15:21 as a duration.

Below are portions of the log from both the asp and avc along with mediainfo reports on both files. I don't know how it helps or if it does at all, but I was wondering if maybe information isn't being passed to the encoder properly?

Edit: Trying to figure this stuff out I just discovered where the ASP vs AVC problem came up for me.

Open Handbrake
Select the Video Tab
Choose Container MKV
Choose Container MP4
Video Tab changes from an RF slider to a QP slider

Now that I see why one of my logs showed a QP12 encode, I think I see why and it appears not to be related to the 13:15:21 bug. I'll leave the below log/media info anyway.

Again, both of the below showed a duration of 13:15:21 in VLC 1.1.9 while an MKV encode of the same source I mentioned in my above post shows the proper duration in VLC.


Portion of Handbrake Log ASP + MediaInfo

Code: Select all

[01:35:07] starting job
[01:35:07] sync: expecting 126477 video frames
[01:35:07] job configuration:
[01:35:07]  * source
[01:35:07]    + E:\Movies_To_Encode\THE_MOVIE\VIDEO_TS
[01:35:07]    + title 2, chapter(s) 1 to 21
[01:35:07]  * destination
[01:35:07]    + C:\Users\<removed>\Desktop\The Movie-2.m4v
[01:35:07]    + container: MPEG-4 (.mp4 and .m4v)
[01:35:07]      + chapter markers
[01:35:07]  * video track
[01:35:07]    + decoder: mpeg2
[01:35:07]      + bitrate 9800 kbps
[01:35:07]    + frame rate: same as source (around 23.976 fps)
[01:35:07]    + loose anamorphic
[01:35:07]      + storage dimensions: 720 * 480 -> 240 * 112, crop 62/66/8/8, mod 0
[01:35:07]      + pixel aspect ratio: 112 / 101
[01:35:07]      + display dimensions: 266 * 112
[01:35:07]    + encoder: FFmpeg MPEG-4
[01:35:07]      + quality: 12.00 (QP)
[01:35:07]  * subtitle track 1, English (id 0x21bd) Picture [VOBSUB] -> Pass-Through
[01:35:07]  * audio track 0
[01:35:07]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[01:35:07]      + bitrate: 448 kbps, samplerate: 48000 Hz
[01:35:07]    + mixdown: Dolby Pro Logic II
[01:35:07]    + encoder: faac
[01:35:07]      + bitrate: 160 kbps, samplerate: 48000 Hz

MediaInfo
General
Complete name                    : C:\Users\<removed>\Desktop\The Movie-2.m4v
Format                           : MPEG-4
Format profile                   : Base Media / Version 2
Codec ID                         : mp42
File size                        : 130 MiB
Duration                         : 1h 28mn
Overall bit rate                 : 207 Kbps
Encoded date                     : UTC 2011-05-09 07:35:07
Tagged date                      : UTC 2011-05-09 07:42:38
Writing application              : HandBrake 3975svn 2011050901

Video
ID                               : 1
Format                           : MPEG-4 Visual
Format profile                   : Simple@L1
Format settings, BVOP            : No
Format settings, QPel            : No
Format settings, GMC             : No warppoints
Format settings, Matrix          : Default (H.263)
Codec ID                         : 20
Duration                         : 1h 28mn
Bit rate mode                    : Variable
Bit rate                         : 40.1 Kbps
Maximum bit rate                 : 3 895 Mbps
Width                            : 240 pixels
Height                           : 112 pixels
Display aspect ratio             : 2.35:1
Frame rate mode                  : Variable
Frame rate                       : 23.976 fps
Minimum frame rate               : 13.278 fps
Maximum frame rate               : 201.342 fps

Portion of Handbrake Log AVC + MediaInfo

Code: Select all

[07:43:12] starting job
[07:43:12] sync: expecting 126477 video frames
[07:43:12] job configuration:
[07:43:12]  * source
[07:43:12]    + E:\Movies_To_Encode\THE_MOVIE\VIDEO_TS
[07:43:12]    + title 2, chapter(s) 1 to 21
[07:43:12]  * destination
[07:43:12]    + C:\Users\<removed>\Desktop\The Movie-2.m4v
[07:43:12]    + container: MPEG-4 (.mp4 and .m4v)
[07:43:12]      + chapter markers
[07:43:12]  * video track
[07:43:12]    + decoder: mpeg2
[07:43:12]      + bitrate 9800 kbps
[07:43:12]    + frame rate: same as source (around 23.976 fps)
[07:43:12]    + loose anamorphic
[07:43:12]      + storage dimensions: 720 * 480 -> 240 * 112, crop 62/66/8/8, mod 0
[07:43:12]      + pixel aspect ratio: 448 / 405
[07:43:12]      + display dimensions: 265 * 112
[07:43:12]    + encoder: x264
[07:43:12]      + options: ref=2:bframes=2:subq=6:mixed-refs=0:weightb=0:8x8dct=0:trellis=0
[07:43:12]      + quality: 18.50 (RF)
[07:43:12]  * subtitle track 1, English (id 0x21bd) Picture [VOBSUB] -> Pass-Through
[07:43:12]  * audio track 0
[07:43:12]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[07:43:12]      + bitrate: 448 kbps, samplerate: 48000 Hz
[07:43:12]    + mixdown: Dolby Pro Logic II
[07:43:12]    + encoder: faac
[07:43:12]      + bitrate: 160 kbps, samplerate: 48000 Hz

MediaInfo
General
Complete name                    : C:\Users\<removed>\Desktop\The Movie-2.m4v
Format                           : MPEG-4
Format profile                   : Base Media / Version 2
Codec ID                         : mp42
File size                        : 179 MiB
Duration                         : 1h 28mn
Overall bit rate                 : 284 Kbps
Encoded date                     : UTC 2011-05-09 13:43:12
Tagged date                      : UTC 2011-05-09 13:54:24
Writing application              : HandBrake 3975svn 2011050901

Video
ID                               : 1
Format                           : AVC
Format/Info                      : Advanced Video Codec
Format profile                   : Main@L1.1
Format settings, CABAC           : Yes
Format settings, ReFrames        : 4 frames
Format settings, GOP             : M=3, N=32
Codec ID                         : avc1
Codec ID/Info                    : Advanced Video Coding
Duration                         : 1h 28mn
Bit rate mode                    : Variable
Bit rate                         : 116 Kbps
Width                            : 240 pixels
Height                           : 112 pixels
Display aspect ratio             : 2.35:1
Frame rate mode                  : Variable
Frame rate                       : 23.976 fps
Minimum frame rate               : 13.278 fps
Maximum frame rate               : 201.342 fps

[marcbau]

Encoded again the same video only with MKV - duration is correct now, but I do not like to use MKV as it should be playable on iPad, iPhone and so on.

[Deleted User 11865]

Encoded again the same video only with MKV - duration is correct now, but I do not like to use MKV as it should be playable on iPad, iPhone and so on.

Well, this would tend to indicate the issue is specific to the MP4 muxer then. In which case I have no idea as to what's causing the issue.

Note that VOBSUB subtitles won't play on Apple devices, so if you want subtitles on the devices you mentioned, you'll need to burn them in.

[marcbau]

I'm not sure what "burn in" means. I'd like to keep them, but only optionally as I do not need them personally today... it's only to have a "complete" file.

Who is responsible for the MP4 muxer and who can analyse this issue to the root cause and finally - fix it?

[Deleted User 11865]

I'm not sure what "burn in" means.

Rendered in the video. That's what the "burned in" checkbox is for.

[marcbau]

I do not like this "burn in".

Who is responsible for the MP4 muxer and who can analyse this issue to the root cause and finally - fix it?

[GregiBoy]

Why do you not download the IOS version of VLC for the iPad, iPhone and use the MKV container?

All your problems will then disappear!

[Deleted User 11865]

Why do you not download the IOS version of VLC for the iPad, iPhone and use the MKV container?

All your problems will then disappear!

I can easily imagine why someone wouldn't want to do that. It's no longer available in the App Store, so you have to jailbreak your device (which can be a major inconvenience); also, no hardware-accelerated decoding was available last time I checked, so HD playback is probably still a no-go.

Edit: on top of that, it doesn't remedy the issue (which affects players like VLC etc.).

As for fixing it, no one is specifically responsible for the MP4 muxer at the moment; it'll be fixed if and when someone has the time to look at it and can figure it out.

[GregiBoy]

Sorry. Forgot that Apple were being their usual bloody minded selves!!

I also meant to use VLC to play the MKV container which does not seem to have the problems.

[marcbau]

There are several reasons... for M4V, but on the end of the day its only Apple compatibilty. I started first with MKV and switched over to M4V after I learned that MKV will never work on iPads. Additionally streaming of M4V seems to be much more fluent than streaming MKV via WLAN to MediaCenter and I do not use VLC at all and no hardware-accelerated decoding is really something not acceptable. It's the same on HTPC systems based on Atom where VLC is really VERY bad.

Alternatives do not really solve the bug...

[Deleted User 11865]

Alternatives do not really solve the bug...

Indeed. But developers work on HandBrake in their free time and are often busy, so not all bugs can be fixed quickly.

[marcbau]

But is it Handbrake internal or any external libraries Handbrake rely on that may cannot fixed by the Handbrake developers?

[s55]

Someone will have to investigate where the issue actually lies. no-one has done this yet, so it's not possible to say if it's a HandBrake or libmp4v2 issue.

This is an open-source project, not a software company. There isn't anyone assigned to this problem. Either someone can submit a patch or someone closer to the project who's interested can look into it. Either way, it could be a while.

[Tree Dude]

I am also having this issue in svn3986. I generally use m4v and add soft subs to all my movies. The files actually show 13:15:12 as the length in explorer.

I will update to the latest nightly and post a log if it happens again.

[s55]

It's not fixed ...