Okay, one last one with perfect audio sync success but... just tested the same video with my older version of HandBrake- Version 0.9.2 (2008021900).
The resulting video has perfect audio sync using the default AppleTV setting (though I did change the Quality setting to constant quality).
Hoping one of the developers can help and provide some guidance (or I'm happy to do additional testing if needed) on why the latest version has sync issues on this film...
Otherwise, any suggestions on settings for version 0.9.2 that can approximate the video results and file size of 0.9.4 (while still maintaining the proper sync of the 0.9.2).
Code: Select all
[18:15:58] hb_init: checking cpu count
[18:15:58] hb_init: starting libhb thread
[18:15:58] thread b0292000 started ("libhb")
[18:16:17] macgui: trying to open video_ts folder (parent directory chosen)
[18:16:17] hb_scan: path=/Volumes/Videos/Jay and Silent Bob Strike Back (2001), title_index=0
[18:16:17] thread b0314000 started ("scan")
[18:16:17] scan: trying to open with libdvdread
[18:16:17] scan: DVD has 9 title(s)
[18:16:17] scan: scanning title 1
[18:16:17] scan: opening IFO for VTS 1
[18:16:17] pgc_id: 1, pgn: 1: pgc: 0x1930db40
[18:16:17] dvd: Skipping multi-angle cells 0-1
[18:16:17] dvd: Skipping multi-angle cells 20-21
[18:16:17] dvd: Skipping multi-angle cells 22-23
[18:16:17] scan: vts=1, ttn=1, cells=0->24, blocks=0->3049467, 3047687 blocks
[18:16:17] scan: duration is 01:44:18 (6258153 ms)
[18:16:17] scan: checking audio 1
[18:16:17] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:17] scan: checking audio 2
[18:16:17] scan: id=81bd, lang=Francais (AC3), 3cc=fra
[18:16:17] scan: checking audio 3
[18:16:17] scan: id=82bd, lang=English (AC3), 3cc=eng
[18:16:17] scan: checking audio 4
[18:16:17] scan: audio channel is not active
[18:16:17] scan: checking subtitle 1
[18:16:17] scan: id=22bd, lang=English, 3cc=eng
[18:16:17] scan: checking subtitle 2
[18:16:17] scan: id=21bd, lang=Espanol, 3cc=spa
[18:16:17] scan: checking subtitle 3
[18:16:17] scan: subtitle channel is not active
[18:16:17] scan: title 1 has 19 chapters
[18:16:17] dvd: Skipping multi-angle cells 0-1
[18:16:17] dvd: Skipping multi-angle cells 20-21
[18:16:17] dvd: Skipping multi-angle cells 22-23
[18:16:17] scan: chap 1 c=0->2, b=0->136394 (135437), 268404 ms
[18:16:17] scan: chap 2 c=3->3, b=136395->350925 (214531), 492548 ms
[18:16:17] scan: chap 3 c=4->4, b=350926->449628 (98703), 213198 ms
[18:16:17] scan: chap 4 c=5->5, b=449629->644144 (194516), 446479 ms
[18:16:17] scan: chap 5 c=6->6, b=644145->787963 (143819), 331577 ms
[18:16:17] scan: chap 6 c=7->7, b=787964->897442 (109479), 236403 ms
[18:16:17] scan: chap 7 c=8->8, b=897443->1175496 (278054), 597783 ms
[18:16:17] scan: chap 8 c=9->9, b=1175497->1357265 (181769), 396473 ms
[18:16:17] scan: chap 9 c=10->10, b=1357266->1581124 (223859), 501555 ms
[18:16:17] scan: chap 10 c=11->11, b=1581125->1693996 (112872), 241220 ms
[18:16:17] scan: chap 11 c=12->13, b=1693997->1761133 (67137), 142453 ms
[18:16:17] scan: chap 12 c=14->14, b=1761134->1869092 (107959), 245343 ms
[18:16:17] scan: chap 13 c=15->15, b=1869093->2038723 (169631), 364292 ms
[18:16:17] scan: chap 14 c=16->16, b=2038724->2197158 (158435), 374290 ms
[18:16:17] scan: chap 15 c=17->17, b=2197159->2440025 (242867), 545599 ms
[18:16:17] scan: chap 16 c=18->18, b=2440026->2510331 (70306), 149315 ms
[18:16:17] scan: chap 17 c=19->21, b=2510332->2562450 (51489), 92513 ms
[18:16:17] scan: chap 18 c=22->23, b=2562451->3049359 (486716), 617699 ms
[18:16:17] scan: chap 19 c=24->24, b=3049360->3049467 (108), 1000 ms
[18:16:17] scan: aspect = 16
[18:16:17] scan: scanning title 2
[18:16:17] scan: opening IFO for VTS 1
[18:16:18] pgc_id: 2, pgn: 1: pgc: 0xa164c0
[18:16:18] dvd: Skipping multi-angle cells 0-1
[18:16:18] dvd: Skipping multi-angle cells 20-21
[18:16:18] dvd: Skipping multi-angle cells 22-23
[18:16:18] scan: vts=1, ttn=2, cells=0->24, blocks=0->3049467, 3047687 blocks
[18:16:18] scan: duration is 01:44:18 (6258153 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: checking audio 2
[18:16:18] scan: id=81bd, lang=Francais (AC3), 3cc=fra
[18:16:18] scan: checking audio 3
[18:16:18] scan: id=82bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: checking audio 4
[18:16:18] scan: id=83bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: checking subtitle 1
[18:16:18] scan: id=22bd, lang=English, 3cc=eng
[18:16:18] scan: checking subtitle 2
[18:16:18] scan: id=21bd, lang=Espanol, 3cc=spa
[18:16:18] scan: checking subtitle 3
[18:16:18] scan: subtitle channel is not active
[18:16:18] scan: title 2 has 19 chapters
[18:16:18] dvd: Skipping multi-angle cells 0-1
[18:16:18] dvd: Skipping multi-angle cells 20-21
[18:16:18] dvd: Skipping multi-angle cells 22-23
[18:16:18] scan: chap 1 c=0->2, b=0->136394 (135437), 268404 ms
[18:16:18] scan: chap 2 c=3->3, b=136395->350925 (214531), 492548 ms
[18:16:18] scan: chap 3 c=4->4, b=350926->449628 (98703), 213198 ms
[18:16:18] scan: chap 4 c=5->5, b=449629->644144 (194516), 446479 ms
[18:16:18] scan: chap 5 c=6->6, b=644145->787963 (143819), 331577 ms
[18:16:18] scan: chap 6 c=7->7, b=787964->897442 (109479), 236403 ms
[18:16:18] scan: chap 7 c=8->8, b=897443->1175496 (278054), 597783 ms
[18:16:18] scan: chap 8 c=9->9, b=1175497->1357265 (181769), 396473 ms
[18:16:18] scan: chap 9 c=10->10, b=1357266->1581124 (223859), 501555 ms
[18:16:18] scan: chap 10 c=11->11, b=1581125->1693996 (112872), 241220 ms
[18:16:18] scan: chap 11 c=12->13, b=1693997->1761133 (67137), 142453 ms
[18:16:18] scan: chap 12 c=14->14, b=1761134->1869092 (107959), 245343 ms
[18:16:18] scan: chap 13 c=15->15, b=1869093->2038723 (169631), 364292 ms
[18:16:18] scan: chap 14 c=16->16, b=2038724->2197158 (158435), 374290 ms
[18:16:18] scan: chap 15 c=17->17, b=2197159->2440025 (242867), 545599 ms
[18:16:18] scan: chap 16 c=18->18, b=2440026->2510331 (70306), 149315 ms
[18:16:18] scan: chap 17 c=19->21, b=2510332->2562450 (51489), 92513 ms
[18:16:18] scan: chap 18 c=22->23, b=2562451->3049359 (486716), 617699 ms
[18:16:18] scan: chap 19 c=24->24, b=3049360->3049467 (108), 1000 ms
[18:16:18] scan: aspect = 16
[18:16:18] scan: scanning title 3
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 1, pgn: 1: pgc: 0x1723a530
[18:16:18] scan: vts=2, ttn=1, cells=0->0, blocks=179381->179384, 4 blocks
[18:16:18] scan: duration is 00:00:00 (176 ms)
[18:16:18] scan: ignoring title (too short)
[18:16:18] scan: scanning title 4
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 2, pgn: 1: pgc: 0xa164c0
[18:16:18] scan: vts=2, ttn=2, cells=0->1, blocks=0->179384, 25073 blocks
[18:16:18] scan: duration is 00:01:04 (64176 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 4 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=0->25068 (25069), 64000 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 176 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: scanning title 5
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 3, pgn: 1: pgc: 0x19307cd0
[18:16:18] scan: vts=2, ttn=3, cells=0->1, blocks=25069->179384, 12275 blocks
[18:16:18] scan: duration is 00:00:31 (31020 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 5 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=25069->37339 (12271), 30845 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 174 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: scanning title 6
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 4, pgn: 1: pgc: 0x921540
[18:16:18] scan: vts=2, ttn=4, cells=0->1, blocks=37340->179384, 43961 blocks
[18:16:18] scan: duration is 00:01:51 (111166 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 6 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=37340->81296 (43957), 110988 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 177 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: scanning title 7
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 5, pgn: 1: pgc: 0x172797f0
[18:16:18] scan: vts=2, ttn=5, cells=0->1, blocks=81297->179384, 34670 blocks
[18:16:18] scan: duration is 00:01:28 (88000 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 7 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=81297->115962 (34666), 87822 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 177 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: scanning title 8
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 6, pgn: 1: pgc: 0xa16bd0
[18:16:18] scan: vts=2, ttn=6, cells=0->1, blocks=115963->179384, 42848 blocks
[18:16:18] scan: duration is 00:01:48 (108010 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 8 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=115963->158806 (42844), 107832 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 177 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: scanning title 9
[18:16:18] scan: opening IFO for VTS 2
[18:16:18] pgc_id: 7, pgn: 1: pgc: 0xd23810
[18:16:18] scan: vts=2, ttn=7, cells=0->1, blocks=158807->179384, 20578 blocks
[18:16:18] scan: duration is 00:00:52 (52010 ms)
[18:16:18] scan: checking audio 1
[18:16:18] scan: id=80bd, lang=English (AC3), 3cc=eng
[18:16:18] scan: title 9 has 2 chapters
[18:16:18] scan: chap 1 c=0->0, b=158807->179380 (20574), 51831 ms
[18:16:18] scan: chap 2 c=1->1, b=179381->179384 (4), 178 ms
[18:16:18] scan: aspect = 12
[18:16:18] scan: decoding previews for title 1
[18:16:24] dvd: Skipping multi-angle cells 0-1
[18:16:24] scan: preview 1
[18:16:24] scan: AC3, rate=48000Hz, bitrate=448000
[18:16:24] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:24] scan: AC3, rate=48000Hz, bitrate=192000
[18:16:24] scan: preview 2
[18:16:24] scan: preview 3
[18:16:24] scan: preview 4
[18:16:25] scan: preview 5
[18:16:25] scan: preview 6
[18:16:25] Title's mostly progressive NTSC, setting fps to 23.976
[18:16:25] scan: preview 7
[18:16:25] dvd: Skipping multi-angle cells 21-21
[18:16:25] scan: preview 8
[18:16:25] dvd: Skipping multi-angle cells 22-23
[18:16:25] scan: preview 9
[18:16:25] dvd: Skipping multi-angle cells 23-23
[18:16:25] scan: preview 10
[18:16:25] scan: 720x480, 23.976 fps, autocrop = 56/58/6/4
[18:16:25] scan: title 2 is duplicate with title 1
[18:16:25] scan: decoding previews for title 4
[18:16:26] scan: preview 1
[18:16:26] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:26] scan: preview 2
[18:16:27] scan: preview 3
[18:16:27] scan: preview 4
[18:16:27] scan: preview 5
[18:16:27] scan: preview 6
[18:16:27] Title's mostly progressive NTSC, setting fps to 23.976
[18:16:27] scan: preview 7
[18:16:27] scan: preview 8
[18:16:27] scan: preview 9
[18:16:27] scan: preview 10
[18:16:28] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[18:16:28] scan: decoding previews for title 5
[18:16:29] scan: preview 1
[18:16:29] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:29] scan: preview 2
[18:16:29] 7.020700s: Film -> Video
[18:16:29] scan: preview 3
[18:16:29] scan: preview 4
[18:16:29] scan: preview 5
[18:16:29] scan: preview 6
[18:16:29] 18.598934s: Video -> Film
[18:16:29] scan: preview 7
[18:16:29] scan: preview 8
[18:16:29] scan: preview 9
[18:16:30] scan: preview 10
[18:16:30] scan: 720x480, 29.970 fps, autocrop = 0/0/8/2
[18:16:30] scan: decoding previews for title 6
[18:16:31] scan: preview 1
[18:16:31] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:31] scan: preview 2
[18:16:31] scan: preview 3
[18:16:31] scan: preview 4
[18:16:31] 40.687668s: Film -> Video
[18:16:31] scan: preview 5
[18:16:31] 48.545509s: Video -> Film
[18:16:31] scan: preview 6
[18:16:31] scan: preview 7
[18:16:32] 67.114067s: Film -> Video
[18:16:32] scan: preview 8
[18:16:32] 78.241844s: Video -> Film
[18:16:32] scan: preview 9
[18:16:32] scan: preview 10
[18:16:32] 97.811401s: Film -> Video
[18:16:32] scan: 720x480, 29.970 fps, autocrop = 0/0/4/2
[18:16:32] scan: decoding previews for title 7
[18:16:33] scan: preview 1
[18:16:33] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:33] 12.509511s: Video -> Film
[18:16:33] scan: preview 2
[18:16:33] scan: preview 3
[18:16:33] scan: preview 4
[18:16:34] scan: preview 5
[18:16:34] scan: preview 6
[18:16:34] Title's mostly progressive NTSC, setting fps to 23.976
[18:16:34] scan: preview 7
[18:16:34] scan: preview 8
[18:16:34] scan: preview 9
[18:16:35] scan: preview 10
[18:16:35] scan: 720x480, 23.976 fps, autocrop = 4/0/12/4
[18:16:35] scan: decoding previews for title 8
[18:16:36] scan: preview 1
[18:16:36] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:36] scan: preview 2
[18:16:36] scan: preview 3
[18:16:36] scan: preview 4
[18:16:36] scan: preview 5
[18:16:36] scan: preview 6
[18:16:36] Title's mostly progressive NTSC, setting fps to 23.976
[18:16:36] scan: preview 7
[18:16:36] scan: preview 8
[18:16:37] scan: preview 9
[18:16:37] scan: preview 10
[18:16:37] scan: 720x480, 23.976 fps, autocrop = 0/0/8/6
[18:16:37] scan: decoding previews for title 9
[18:16:38] scan: preview 1
[18:16:38] scan: AC3, rate=48000Hz, bitrate=384000
[18:16:38] scan: preview 2
[18:16:38] scan: preview 3
[18:16:38] scan: preview 4
[18:16:38] scan: preview 5
[18:16:38] scan: preview 6
[18:16:38] scan: preview 7
[18:16:39] scan: preview 8
[18:16:39] scan: preview 9
[18:16:39] scan: preview 10
[18:16:39] scan: 720x480, 29.970 fps, autocrop = 0/0/4/2
[18:16:39] scan: title (0) job->width:704, job->height:304
[18:16:39] scan: title (1) job->width:640, job->height:480
[18:16:39] scan: title (2) job->width:624, job->height:480
[18:16:39] scan: title (3) job->width:640, job->height:480
[18:16:39] scan: title (4) job->width:624, job->height:480
[18:16:39] scan: title (5) job->width:624, job->height:480
[18:16:39] scan: title (6) job->width:640, job->height:480
[18:16:39] thread b0314000 exited ("scan")
[18:16:39] thread b0314000 joined ("scan")
[18:16:39] libhb: scan thread found 7 valid title(s)
[18:20:32] thread b018e000 started ("work")
[18:20:32] 1 job(s) to process
[18:20:32] starting job
[18:20:32] + device /Volumes/Videos/Jay and Silent Bob Strike Back (2001)
[18:20:32] + title 1, chapter(s) 1 to 19
[18:20:32] + 720x480 -> 710x366, crop 56/58/6/4
[18:20:32] + video frame rate: 23.976 fps
[18:20:32] + video quality 0.65
[18:20:32] + PixelRatio: 1, width:710, height: 366
[18:20:32] + encoder x264
[18:20:32] + x264 options: bframes=3:ref=1:subq=5:me=umh:no-fast-pskip=1:trellis=1:cabac=0
[18:20:32] + audio 160 kbps, 48000 Hz
[18:20:32] + encoder faac
[18:20:32] + 80bd, English (AC3) (4.1 ch)
[18:20:32] + Requested mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[18:20:32] + Actual mixdown: Stereo (HB_AMIXDOWN_STEREO)
[18:20:32] + 80bd, English (AC3) (4.1 ch)
[18:20:32] + Requested mixdown: AC-3 Pass-through (HB_AMIXDOWN_AC3)
[18:20:32] + Actual mixdown: AC-3 Pass-through (HB_AMIXDOWN_AC3)
[18:20:32] thread b0314000 started ("reader")
[18:20:32] + output: /Users/lee/Movies/Batch Encode/Jay and Silent Bob Strike Back (2001).m4v
[18:20:32] thread b03a7000 started ("muxer")
[18:20:32] thread b04ab000 started ("MPEG-2 decoder (libmpeg2)")
[18:20:32] thread b052d000 started ("Renderer")
[18:20:32] encx264: keyint-min: 24, keyint-max: 240
[18:20:32] encx264: encoding with stored aspect 32/27
[18:20:32] encx264: Encoding at constant RF 17.850002
[18:20:32] encx264: opening libx264 (pass 0)
x264 [warning]: width or height not divisible by 16 (710x366), compression will suffer.
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 SSE3 SSSE3 Cache64
[18:20:32] thread b0631000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[18:20:32] thread b06b3000 started ("AC3 decoder")
[18:20:32] thread b0735000 started ("AAC encoder (libfaac)")
No accelerated IMDCT transform found
[18:20:32] thread b07b7000 started ("AC3 decoder")
[18:20:32] sync: expecting 150069 video frames
[18:20:38] dvd: Skipping multi-angle cells 0-1
[18:20:38] dvd: Beginning of Cell (0) at block 0
[18:20:38] 0.380733s: Film -> Video
[18:20:38] 0.414100s: Video -> Film
[18:20:38] sync: first pts is 31263
[18:20:39] Disabled extra audio track 2
[18:20:39] macgui: Using preset: Custom
[18:20:40] 9.973634s: Film -> Video
[18:20:42] 23.937599s: Video -> Film
[18:20:45] dvd: End of Cell (0) at block 24514
[18:20:45] dvd: Beginning of Cell (2) at block 25902
[18:20:45] Sync: Audio discontinuity (sequence: vid 11043 aud 11086) (pts -87449 < 2551 < 92551)
[18:20:45] Sync: Audio joined Video after discontinuity at PTS 3409257
[18:20:45] Sync: Audio discontinuity (sequence: vid 11043 aud 0) (pts -87449 < 2551 < 92551)
[18:20:45] Sync: Video PTS discontinuity second (current buffer start=3427655, next buffer start=21073)
[18:20:46] Sync: Audio joined Video after discontinuity at PTS 16951
[18:21:43] dvd: End of Cell (2) at block 136027
[18:21:43] dvd: Beginning of Cell (3) at block 136395
[18:21:43] dvd: Chapter Break Cell Found
[18:21:43] MPEG2: Chapter Break Cell Found, searching for GOP
[18:21:43] MPEG2: Group of pictures found, searching for I-Frame
[18:21:43] MPEG2: I-Frame Found
[18:21:43] MPEG2: Chapter Break Inserted
[18:21:43] 231.865555s: Film -> Video
[18:21:43] work Renderer: Copying Chapter Break @ 24241717
[18:21:43] 232.015686s: Video -> Film
[18:23:34] dvd: End of Cell (3) at block 350517
[18:23:34] dvd: Beginning of Cell (4) at block 350926
[18:23:34] dvd: Chapter Break Cell Found
[18:23:34] MPEG2: Chapter Break Cell Found, searching for GOP
[18:23:34] MPEG2: Group of pictures found, searching for I-Frame
[18:23:34] MPEG2: I-Frame Found
[18:23:34] MPEG2: Chapter Break Inserted
[18:23:34] 724.524353s: Film -> Video
[18:23:34] 724.674561s: Video -> Film
[18:23:34] work Renderer: Copying Chapter Break @ 68581012
[18:24:25] dvd: End of Cell (4) at block 449444
[18:24:25] dvd: Beginning of Cell (5) at block 449629
[18:24:25] dvd: Chapter Break Cell Found
[18:24:25] MPEG2: Chapter Break Cell Found, searching for GOP
[18:24:25] MPEG2: Group of pictures found, searching for I-Frame
[18:24:25] MPEG2: I-Frame Found
[18:24:25] MPEG2: Chapter Break Inserted
[18:24:25] 937.770752s: Film -> Video
[18:24:25] 937.920898s: Video -> Film
[18:24:25] work Renderer: Copying Chapter Break @ 87773936
[18:24:29] 957.807373s: Film -> Video
[18:24:29] 957.890869s: Video -> Film
[18:26:10] dvd: End of Cell (5) at block 643876
[18:26:10] dvd: Beginning of Cell (6) at block 644145
[18:26:10] dvd: Chapter Break Cell Found
[18:26:10] MPEG2: Chapter Break Cell Found, searching for GOP
[18:26:10] MPEG2: Group of pictures found, searching for I-Frame
[18:26:10] MPEG2: I-Frame Found
[18:26:10] MPEG2: Chapter Break Inserted
[18:26:10] 1384.350220s: Film -> Video
[18:26:10] work Renderer: Copying Chapter Break @ 127965337
[18:26:10] 1384.500366s: Video -> Film
[18:27:27] dvd: End of Cell (6) at block 787697
[18:27:27] 1716.498657s: Film -> Video
[18:27:27] dvd: Beginning of Cell (7) at block 787964
[18:27:27] dvd: Chapter Break Cell Found
[18:27:27] MPEG2: Chapter Break Cell Found, searching for GOP
[18:27:27] MPEG2: Group of pictures found, searching for I-Frame
[18:27:27] MPEG2: I-Frame Found
[18:27:27] MPEG2: Chapter Break Inserted
[18:27:27] 1716.665649s: Video -> Film
[18:27:28] work Renderer: Copying Chapter Break @ 157871463
[18:28:24] dvd: End of Cell (7) at block 897232
[18:28:24] dvd: Beginning of Cell (8) at block 897443
[18:28:24] dvd: Chapter Break Cell Found
[18:28:24] MPEG2: Chapter Break Cell Found, searching for GOP
[18:28:24] MPEG2: Group of pictures found, searching for I-Frame
[18:28:24] MPEG2: I-Frame Found
[18:28:24] MPEG2: Chapter Break Inserted
[18:28:24] 1953.752319s: Film -> Video
[18:28:24] work Renderer: Copying Chapter Break @ 179211532
[18:28:24] 1953.902588s: Video -> Film
[18:30:49] dvd: End of Cell (8) at block 1175008
[18:30:50] 2552.166992s: Film -> Video
[18:30:50] dvd: Beginning of Cell (9) at block 1175497
[18:30:50] dvd: Chapter Break Cell Found
[18:30:50] MPEG2: Chapter Break Cell Found, searching for GOP
[18:30:50] MPEG2: Group of pictures found, searching for I-Frame
[18:30:50] MPEG2: I-Frame Found
[18:30:50] MPEG2: Chapter Break Inserted
[18:30:50] 2552.333740s: Video -> Film
[18:30:50] work Renderer: Copying Chapter Break @ 233081598
[18:32:24] dvd: End of Cell (9) at block 1357037
[18:32:24] 2948.729492s: Film -> Video
[18:32:24] dvd: Beginning of Cell (10) at block 1357266
[18:32:24] dvd: Chapter Break Cell Found
[18:32:24] MPEG2: Chapter Break Cell Found, searching for GOP
[18:32:24] MPEG2: Group of pictures found, searching for I-Frame
[18:32:24] MPEG2: I-Frame Found
[18:32:24] MPEG2: Chapter Break Inserted
[18:32:24] 2948.896484s: Video -> Film
[18:32:24] work Renderer: Copying Chapter Break @ 268772253
[18:33:25] 3217.298096s: Film -> Video
[18:33:25] 3217.498291s: Video -> Film
[18:34:20] dvd: End of Cell (10) at block 1580779
[18:34:20] 3450.414307s: Film -> Video
[18:34:20] dvd: Beginning of Cell (11) at block 1581125
[18:34:20] dvd: Chapter Break Cell Found
[18:34:20] MPEG2: Chapter Break Cell Found, searching for GOP
[18:34:20] MPEG2: Group of pictures found, searching for I-Frame
[18:34:20] MPEG2: I-Frame Found
[18:34:20] MPEG2: Chapter Break Inserted
[18:34:20] 3450.564209s: Video -> Film
[18:34:20] work Renderer: Copying Chapter Break @ 313922358
[18:35:16] dvd: End of Cell (11) at block 1693564
[18:35:17] dvd: Beginning of Cell (12) at block 1693997
[18:35:17] dvd: Chapter Break Cell Found
[18:35:17] MPEG2: Chapter Break Cell Found, searching for GOP
[18:35:17] MPEG2: Group of pictures found, searching for I-Frame
[18:35:17] MPEG2: I-Frame Found
[18:35:17] MPEG2: Chapter Break Inserted
[18:35:17] 3691.822266s: Film -> Video
[18:35:17] 3691.972168s: Video -> Film
[18:35:17] work Renderer: Copying Chapter Break @ 335637802
[18:35:45] dvd: End of Cell (12) at block 1750283
[18:35:46] 3811.274658s: Film -> Video
[18:35:46] dvd: Beginning of Cell (13) at block 1750668
[18:35:46] 3811.424805s: Video -> Film
[18:35:51] dvd: End of Cell (13) at block 1760833
[18:35:51] dvd: Beginning of Cell (14) at block 1761134
[18:35:51] dvd: Chapter Break Cell Found
[18:35:51] MPEG2: Chapter Break Cell Found, searching for GOP
[18:35:51] MPEG2: Group of pictures found, searching for I-Frame
[18:35:51] MPEG2: I-Frame Found
[18:35:51] MPEG2: Chapter Break Inserted
[18:35:51] 3834.631348s: Film -> Video
[18:35:51] work Renderer: Copying Chapter Break @ 348490642
[18:35:51] 3834.781494s: Video -> Film
[18:36:46] dvd: End of Cell (14) at block 1868821
[18:36:46] 4080.543701s: Film -> Video
[18:36:46] dvd: Beginning of Cell (15) at block 1869093
[18:36:46] dvd: Chapter Break Cell Found
[18:36:46] MPEG2: Chapter Break Cell Found, searching for GOP
[18:36:46] MPEG2: Group of pictures found, searching for I-Frame
[18:36:46] MPEG2: I-Frame Found
[18:36:46] MPEG2: Chapter Break Inserted
[18:36:46] 4080.693604s: Video -> Film
[18:36:46] work Renderer: Copying Chapter Break @ 370634013
[18:38:14] dvd: End of Cell (15) at block 2038263
[18:38:14] 4445.241211s: Film -> Video
[18:38:14] dvd: Beginning of Cell (16) at block 2038724
[18:38:14] dvd: Chapter Break Cell Found
[18:38:14] MPEG2: Chapter Break Cell Found, searching for GOP
[18:38:14] MPEG2: Group of pictures found, searching for I-Frame
[18:38:14] MPEG2: I-Frame Found
[18:38:14] MPEG2: Chapter Break Inserted
[18:38:14] 4445.391602s: Video -> Film
[18:38:14] work Renderer: Copying Chapter Break @ 403456803
[18:39:40] dvd: End of Cell (16) at block 2196715
[18:39:40] 4819.615234s: Film -> Video
[18:39:40] dvd: Beginning of Cell (17) at block 2197159
[18:39:40] dvd: Chapter Break Cell Found
[18:39:40] MPEG2: Chapter Break Cell Found, searching for GOP
[18:39:40] MPEG2: Group of pictures found, searching for I-Frame
[18:39:40] MPEG2: I-Frame Found
[18:39:40] MPEG2: Chapter Break Inserted
[18:39:40] 4819.765625s: Video -> Film
[18:39:41] work Renderer: Copying Chapter Break @ 437150463
[18:41:46] dvd: End of Cell (17) at block 2439635
[18:41:47] 5365.660645s: Film -> Video
[18:41:47] dvd: Beginning of Cell (18) at block 2440026
[18:41:47] dvd: Chapter Break Cell Found
[18:41:47] MPEG2: Chapter Break Cell Found, searching for GOP
[18:41:47] MPEG2: Group of pictures found, searching for I-Frame
[18:41:47] MPEG2: I-Frame Found
[18:41:47] MPEG2: Chapter Break Inserted
[18:41:47] 5365.811035s: Video -> Film
[18:41:47] work Renderer: Copying Chapter Break @ 486294558
[18:42:21] dvd: End of Cell (18) at block 2510049
[18:42:22] dvd: Beginning of Cell (19) at block 2510332
[18:42:22] dvd: Chapter Break Cell Found
[18:42:22] MPEG2: Chapter Break Cell Found, searching for GOP
[18:42:22] MPEG2: Group of pictures found, searching for I-Frame
[18:42:22] MPEG2: I-Frame Found
[18:42:22] MPEG2: Chapter Break Inserted
[18:42:22] 5515.143555s: Film -> Video
[18:42:22] work Renderer: Copying Chapter Break @ 499736737
[18:42:22] 5515.293945s: Video -> Film
[18:42:41] dvd: End of Cell (19) at block 2547287
[18:42:41] dvd: Skipping multi-angle cells 20-21
[18:42:41] dvd: Beginning of Cell (20) at block 2547671
[18:42:41] Sync: Audio discontinuity (sequence: vid 2245258 aud 2245370) (pts -81891 < 8109 < 98109)
[18:42:41] Sync: Audio joined Video after discontinuity at PTS 503026231
[18:42:41] Sync: Audio discontinuity (sequence: vid 2245258 aud 0) (pts -81891 < 8109 < 98109)
[18:42:41] Sync: Video PTS discontinuity second (current buffer start=503046094, next buffer start=25216)
[18:42:41] Sync: Audio joined Video after discontinuity at PTS 13869
[18:42:41] Sync: Audio PTS (13869) < Video PTS (25130) by greater than 100ms, trashing audio to reconverge
[18:42:41] Sync: Audio back in Sync at PTS 16749
[18:42:45] dvd: End of Cell (20) at block 2561581
[18:42:45] dvd: Skipping multi-angle cells 22-23
[18:42:45] 18.698555s: Film -> Video
[18:42:45] dvd: Beginning of Cell (22) at block 2562451
[18:42:45] dvd: Chapter Break Cell Found
[18:42:45] MPEG2: Chapter Break Cell Found, searching for GOP
[18:42:45] MPEG2: Group of pictures found, searching for I-Frame
[18:42:45] MPEG2: I-Frame Found
[18:42:45] MPEG2: Chapter Break Inserted
[18:42:45] 18.848734s: Video -> Film
[18:42:45] work Renderer: Copying Chapter Break @ 508088831
[18:43:38] 229.308929s: Film -> Video
[18:44:58] 619.315308s: Video -> Film
[18:45:02] dvd: End of Cell (22) at block 3049107
[18:45:02] 637.149719s: Film -> Video
[18:45:02] dvd: Beginning of Cell (24) at block 3049360
[18:45:02] dvd: Chapter Break Cell Found
[18:45:02] MPEG2: Chapter Break Cell Found, searching for GOP
[18:45:02] MPEG2: Group of pictures found, searching for I-Frame
[18:45:02] 637.249817s: Video -> Film
[18:45:02] MPEG2: I-Frame Found
[18:45:02] MPEG2: Chapter Break Inserted
[18:45:02] 0.311889s: Film -> Video
[18:45:02] dvd: End of Cell (24) at block 3049423
[18:45:02] Sync: Audio discontinuity (sequence: vid 2463659 aud 2463707) (pts -84535 < 5465 < 95465)
[18:45:02] Sync: Audio joined Video after discontinuity at PTS 57282669
[18:45:02] Sync: Video PTS discontinuity first (current buffer start=57352484, next buffer start=25067)
[18:45:02] work Renderer: Copying Chapter Break @ 563749436
[18:45:02] reader: done
[18:45:02] thread b0314000 exited ("reader")
[18:45:02] Sync: Audio joined Video after discontinuity at PTS 16985
[18:45:02] Sync: Audio PTS (5465) < Video PTS (18351) by greater than 100ms, trashing audio to reconverge
[18:45:02] Sync: Audio back in Sync at PTS 11225
[18:45:02] sync: got 150206 frames, 150069 expected
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] Reader has exited early, inserting silence.
[18:45:02] sync: adding a silent AC-3 frame for track 80bd
[18:45:02] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:03] Reader has exited early, inserting silence.
[18:45:03] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] Reader has exited early, inserting silence.
[18:45:04] sync: adding a silent AC-3 frame for track 80bd
[18:45:04] thread b07b7000 exited ("AC3 decoder")
[18:45:04] thread b0631000 exited ("H.264/AVC encoder (libx264)")
[18:45:04] thread b06b3000 exited ("AC3 decoder")
[18:45:04] thread b04ab000 exited ("MPEG-2 decoder (libmpeg2)")
[18:45:04] thread b04ab000 joined ("MPEG-2 decoder (libmpeg2)")
[18:45:04] thread b052d000 exited ("Renderer")
[18:45:04] thread b052d000 joined ("Renderer")
[18:45:04] render: lost time: 0 (0 frames)
[18:45:04] render: gained time: 0 (0 frames) (0 not accounted for)
[18:45:04] fifo_close: trashing 0 buffer(s)
[18:45:04] fifo_close: trashing 0 buffer(s)
[18:45:04] thread b0631000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:1775 Avg QP:17.80 size: 40242 PSNR Mean Y:46.10 U:49.53 V:50.10 Avg:46.82 Global:46.60
x264 [info]: slice P:64246 Avg QP:19.74 size: 15345 PSNR Mean Y:44.12 U:49.84 V:50.43 Avg:44.95 Global:44.61
x264 [info]: slice B:84185 Avg QP:21.61 size: 5521 PSNR Mean Y:43.34 U:50.10 V:50.65 Avg:44.27 Global:43.91
x264 [info]: mb I I16..4: 23.3% 0.0% 76.7%
x264 [info]: mb P I16..4: 10.8% 0.0% 10.0% P16..4: 32.4% 25.5% 12.0% 0.0% 0.0% skip: 9.3%
x264 [info]: mb B I16..4: 0.8% 0.0% 1.0% B16..8: 30.8% 4.1% 5.2% direct:13.5% skip:44.7%
x264 [info]: SSIM Mean Y:0.9807187
x264 [info]: PSNR Mean Y:43.703 U:49.984 V:50.551 Avg:44.591 Global:44.221 kb/s:1943.57
[18:45:04] thread b0735000 exited ("AAC encoder (libfaac)")
[18:45:04] thread b06b3000 joined ("AC3 decoder")
[18:45:04] thread b0735000 joined ("AAC encoder (libfaac)")
[18:45:04] thread b07b7000 joined ("AC3 decoder")
[18:45:04] thread b0314000 joined ("reader")
[18:45:05] mux: file size, 2002415334 bytes
[18:45:05] mux: track 0, 1522022565 bytes, 1943.57 kbps
[18:45:05] mux: track 1, 125265373 bytes, 159.96 kbps
[18:45:05] mux: track 2, 350830592 bytes, 448.00 kbps
[18:45:05] mux: overhead, 6.72 bytes per frame
[18:45:05] thread b03a7000 exited ("muxer")
[18:45:05] thread b03a7000 joined ("muxer")
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 2 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 0 buffer(s)
[18:45:05] fifo_close: trashing 1 buffer(s)
[18:45:05] Freed 2 buffers of size 512
[18:45:05] Freed 0 buffers of size 1024
[18:45:05] Freed 165 buffers of size 2048
[18:45:05] Freed 0 buffers of size 4096
[18:45:05] Freed 0 buffers of size 8192
[18:45:05] Freed 41 buffers of size 16384
[18:45:05] Freed 0 buffers of size 32768
[18:45:05] Freed 65 buffers of size 518400
[18:45:05] Allocated 34706688 bytes of buffers on this pass and Freed 34706688 bytes, 0 bytes leaked
[18:45:05] thread b018e000 exited ("work")
[18:45:05] thread b018e000 joined ("work")
[18:45:05] libhb: work result = 0