rendered paste body[11:45:27] hb_init: checking cpu count
[11:45:27] hb_init: starting libhb thread
[11:45:27] thread f0081000 started ("libhb")
HandBrake 0.9.2 (2008021900) - http://handbrake.m0k.org/
2 CPUs detected
Opening /Volumes/elaine/Hot Fuzz/VIDEO_TS...
[11:45:27] hb_scan: path=/Volumes/elaine/Hot Fuzz/VIDEO_TS, title_index=1
[11:45:27] thread f0103000 started ("scan")
[11:45:27] scan: trying to open with libdvdread
[11:45:27] scan: DVD has 1 title(s)
[11:45:27] scan: scanning title 1
[11:45:27] scan: opening IFO for VTS 1
[11:45:27] pgc_id: 1, pgn: 1: pgc: 0x700ff0
[11:45:27] scan: vts=1, ttn=1, cells=0->29, blocks=0->2396369, 2396370 blocks
[11:45:27] scan: duration is 02:00:45 (7245010 ms)
[11:45:27] scan: checking audio 1
[11:45:27] scan: id=80bd, lang=English (AC3), 3cc=eng
[11:45:27] scan: checking audio 2
[11:45:27] scan: id=83bd, lang=English (AC3), 3cc=eng
[11:45:27] scan: checking subtitle 1
[11:45:27] scan: id=20bd, lang=English, 3cc=eng
[11:45:27] scan: checking subtitle 2
[11:45:27] scan: id=21bd, lang=English, 3cc=eng
[11:45:27] scan: checking subtitle 3
[11:45:27] scan: id=24bd, lang=English, 3cc=eng
[11:45:27] scan: checking subtitle 4
[11:45:27] scan: id=25bd, lang=English, 3cc=eng
[11:45:27] scan: checking subtitle 5
[11:45:27] scan: id=26bd, lang=English, 3cc=eng
[11:45:27] scan: title 1 has 30 chapters
[11:45:27] scan: chap 1 c=0->0, b=0->53770 (53771), 146208 ms
[11:45:27] scan: chap 2 c=1->1, b=53771->114608 (60838), 176438 ms
[11:45:27] scan: chap 3 c=2->2, b=114609->153748 (39140), 111379 ms
[11:45:27] scan: chap 4 c=3->3, b=153749->253386 (99638), 294563 ms
[11:45:27] scan: chap 5 c=4->4, b=253387->293373 (39987), 116386 ms
[11:45:27] scan: chap 6 c=5->5, b=293374->446900 (153527), 451788 ms
[11:45:27] scan: chap 7 c=6->6, b=446901->532523 (85623), 248555 ms
[11:45:27] scan: chap 8 c=7->7, b=532524->591072 (58549), 178264 ms
[11:45:27] scan: chap 9 c=8->8, b=591073->660434 (69362), 201473 ms
[11:45:27] scan: chap 10 c=9->9, b=660435->725777 (65343), 199284 ms
[11:45:27] scan: chap 11 c=10->10, b=725778->798149 (72372), 219499 ms
[11:45:27] scan: chap 12 c=11->11, b=798150->859791 (61642), 184406 ms
[11:45:27] scan: chap 13 c=12->12, b=859792->914922 (55131), 163276 ms
[11:45:27] scan: chap 14 c=13->13, b=914923->1004042 (89120), 270706 ms
[11:45:27] scan: chap 15 c=14->14, b=1004043->1113089 (109047), 330758 ms
[11:45:27] scan: chap 16 c=15->15, b=1113090->1247745 (134656), 406891 ms
[11:45:27] scan: chap 17 c=16->16, b=1247746->1400366 (152621), 458698 ms
[11:45:27] scan: chap 18 c=17->17, b=1400367->1548367 (148001), 453834 ms
[11:45:27] scan: chap 19 c=18->18, b=1548368->1572636 (24269), 68417 ms
[11:45:27] scan: chap 20 c=19->19, b=1572637->1594828 (22192), 67296 ms
[11:45:27] scan: chap 21 c=20->20, b=1594829->1784835 (190007), 584835 ms
[11:45:27] scan: chap 22 c=21->21, b=1784836->1838068 (53233), 160405 ms
[11:45:27] scan: chap 23 c=22->22, b=1838069->2025397 (187329), 574130 ms
[11:45:27] scan: chap 24 c=23->23, b=2025398->2111917 (86520), 256376 ms
[11:45:27] scan: chap 25 c=24->24, b=2111918->2216635 (104718), 318521 ms
[11:45:27] scan: chap 26 c=25->25, b=2216636->2240383 (23748), 72279 ms
[11:45:27] scan: chap 27 c=26->26, b=2240384->2278148 (37765), 112336 ms
[11:45:27] scan: chap 28 c=27->27, b=2278149->2305149 (27001), 80424 ms
[11:45:27] scan: chap 29 c=28->28, b=2305150->2396365 (91216), 337535 ms
[11:45:27] scan: chap 30 c=29->29, b=2396366->2396369 (4), 33 ms
[11:45:27] scan: aspect = 16
[11:45:27] scan: decoding previews for title 1
Scanning title 1...
Scanning title 1...
[11:45:28] scan: preview 1
Scanning title 1...
[11:45:28] scan: AC3, rate=48000Hz, bitrate=448000
[11:45:28] scan: AC3, rate=48000Hz, bitrate=192000
[11:45:28] scan: preview 2
[11:45:28] scan: preview 3
[11:45:28] scan: preview 4
[11:45:28] scan: preview 5
Scanning title 1...
[11:45:28] scan: preview 6
[11:45:28] Title's mostly progressive NTSC, setting fps to 23.976
[11:45:28] scan: preview 7
[11:45:28] scan: preview 8
[11:45:28] scan: preview 9
[11:45:29] scan: preview 10
Scanning title 1...
[11:45:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 58/60/0/0, aspect 16:9
[11:45:29] scan: title (0) job->width:720, job->height:304
[11:45:29] thread f0103000 exited ("scan")
[11:45:29] thread f0103000 joined ("scan")
[11:45:29] libhb: scan thread found 1 valid title(s)
+ title 1:
+ vts 1, ttn 1, cells 0->29 (2396370 blocks)
+ duration: 02:00:45
+ size: 720x480, aspect: 1.78, 23.976 fps
+ autocrop: 58/60/0/0
+ chapters:
+ 1: cells 0->0, 53771 blocks, duration 00:02:26
+ 2: cells 1->1, 60838 blocks, duration 00:02:56
+ 3: cells 2->2, 39140 blocks, duration 00:01:51
+ 4: cells 3->3, 99638 blocks, duration 00:04:55
+ 5: cells 4->4, 39987 blocks, duration 00:01:56
+ 6: cells 5->5, 153527 blocks, duration 00:07:32
+ 7: cells 6->6, 85623 blocks, duration 00:04:09
+ 8: cells 7->7, 58549 blocks, duration 00:02:58
+ 9: cells 8->8, 69362 blocks, duration 00:03:21
+ 10: cells 9->9, 65343 blocks, duration 00:03:19
+ 11: cells 10->10, 72372 blocks, duration 00:03:39
+ 12: cells 11->11, 61642 blocks, duration 00:03:04
+ 13: cells 12->12, 55131 blocks, duration 00:02:43
+ 14: cells 13->13, 89120 blocks, duration 00:04:31
+ 15: cells 14->14, 109047 blocks, duration 00:05:31
+ 16: cells 15->15, 134656 blocks, duration 00:06:47
+ 17: cells 16->16, 152621 blocks, duration 00:07:39
+ 18: cells 17->17, 148001 blocks, duration 00:07:34
+ 19: cells 18->18, 24269 blocks, duration 00:01:08
+ 20: cells 19->19, 22192 blocks, duration 00:01:07
+ 21: cells 20->20, 190007 blocks, duration 00:09:45
+ 22: cells 21->21, 53233 blocks, duration 00:02:40
+ 23: cells 22->22, 187329 blocks, duration 00:09:34
+ 24: cells 23->23, 86520 blocks, duration 00:04:16
+ 25: cells 24->24, 104718 blocks, duration 00:05:19
+ 26: cells 25->25, 23748 blocks, duration 00:01:12
+ 27: cells 26->26, 37765 blocks, duration 00:01:52
+ 28: cells 27->27, 27001 blocks, duration 00:01:20
+ 29: cells 28->28, 91216 blocks, duration 00:05:38
+ 30: cells 29->29, 4 blocks, duration 00:00:00
+ audio tracks:
+ 1, English (AC3) (5.1 ch), 48000Hz, 448000bps
+ 2, English (AC3) (2.0 ch), 48000Hz, 192000bps
+ subtitle tracks:
+ 1, English (iso639-2: eng)
+ 2, English (iso639-2: eng)
+ 3, English (iso639-2: eng)
+ 4, English (iso639-2: eng)
+ 5, English (iso639-2: eng)
[11:45:29] thread f0103000 started ("work")
[11:45:29] 1 job(s) to process
[11:45:29] starting job
[11:45:29] + device /Volumes/elaine/Hot Fuzz/VIDEO_TS
[11:45:29] + title 1, chapter(s) 1 to 30
[11:45:29] + 720x480 -> 320x160, crop 58/60/0/0
[11:45:29] + video frame rate: 23.976 fps
[11:45:29] + video quality 0.61
[11:45:29] + PixelRatio: 2, width:320, height: 160
[11:45:29] + encoder x264
[11:45:29] + x264 options: ref=4:direct=auto:cabac=0:analyse=all:level=30:vbv-maxrate=3000:vbv-bufsize=1700
[11:45:29] + audio 128 kbps, 44100 Hz
[11:45:29] + encoder faac
[11:45:29] + 80bd, English (AC3) (5.1 ch)
[11:45:29] + Requested mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[11:45:29] + Actual mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[11:45:29] thread f0185000 started ("reader")
[11:45:29] + output: /Users/dani/Movies/Hot Fuzz (nano).m4v
[11:45:29] thread f0207000 started ("muxer")
[11:45:29] thread f0289000 started ("MPEG-2 decoder (libmpeg2)")
[11:45:29] thread f030b000 started ("Renderer")
[11:45:29] encx264: keyint-min: 24, keyint-max: 240
[11:45:29] encx264: encoding with stored aspect 377/320
[11:45:29] encx264: Encoding at constant RF 19.762499
[11:45:29] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=377/320
x264 [info]: using cpu capabilities: Altivec
[11:45:29] thread f038d000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[11:45:29] thread f040f000 started ("AC3 decoder")
[11:45:29] thread f0491000 started ("AAC encoder (libfaac)")
[11:45:29] sync: expecting 173730 video frames
[11:45:29] dvd: Beginning of Cell (0) at block 0
[11:45:29] reader: first SCR 146 scr_offset 25257
[11:45:29] About to add iPod a[11:[11:46:24] dvd: End of Cell (0) at block 53526
[11:46:25] dvd: Beginning of Cell (1) at block 53771
[11:46:25] dvd: Chapter Break Cell Found
[11:46:27] MPEG2: Chapter Break Cell Found, searching for GOP
[11:46:27] MPEG2: Group of pictures found, searching for I-Frame
[11:46:27] MPEG2: I-Frame Found
[11:46:27] MPEG2: Chapter Break Inserted
[11:46:27] sync: audio 0 time went backwards 26 ms, dropped 1 frames (frame 13127557, expected 13129920)
[11:46:27] work Renderer: Copying Chapter Break @ 13158284
[11:47:21] dvd: End of Cell (1) at block 114406
[11:47:22] dvd: Beginning of Cell (2) at block 114609
[11:47:22] dvd: Chapter Break Cell Found
[11:47:24] MPEG2: Chapter Break Cell Found, searching for GOP
[11:47:24] MPEG2: Group of pictures found, searching for I-Frame
[11:47:24] MPEG2: I-Frame Found
[11:47:24] MPEG2: Chapter Break Inserted
[11:47:24] sync: audio 0 time went backwards 6 ms, dropped 1 frames (frame 29058596, expected 29059200)
[11:47:25] work Renderer: Copying Chapter Break @ 29088078
[11:48:01] dvd: End of Cell (2) at block 153522
[11:48:02] dvd: Beginning of Cell (3) at block 153749
[11:48:02] dvd: Chapter Break Cell Found
[11:48:04] MPEG2: Chapter Break Cell Found, searching for GOP
[11:48:04] MPEG2: Group of pictures found, searching for I-Frame
[11:48:04] MPEG2: I-Frame Found
[11:48:04] MPEG2: Chapter Break Inserted
[11:48:04] work Renderer: Copying Chapter Break @ 39157961
[11:49:41] dvd: End of Cell (3) at block 253212
[11:49:41] dvd: Beginning of Cell (4) at block 253387
[11:49:41] dvd: Chapter Break Cell Found
[11:49:44] MPEG2: Chapter Break Cell Found, searching for GOP
[11:49:44] MPEG2: Group of pictures found, searching for I-Frame
[11:49:44] sync: audio 0 time went backwards 46 ms, dropped 2 frames (frame 65656949, expected 65661120)
[11:49:44] MPEG2: I-Frame Found
[11:49:44] MPEG2: Chapter Break Inserted
[11:49:44] sync: video time went backwards 30 ms, dropped 1 frames (frame 65688201, expected 65690964)
[11:50:30] dvd: End of Cell (4) at block 293245
[11:50:30] dvd: Beginning of Cell (5) at block 293374
[11:50:30] dvd: Chapter Break Cell Found
[11:50:33] MPEG2: Chapter Break Cell Found, searching for GOP
[11:50:33] MPEG2: Group of pictures found, searching for I-Frame
[11:50:33] MPEG2: I-Frame Found
[11:50:33] MPEG2: Chapter Break Inserted
[11:50:34] work Renderer: Copying Chapter Break @ 76208496
[11:53:45] dvd: End of Cell (5) at block 446676
[11:53:45] dvd: Beginning of Cell (6) at block 446901
[11:53:45] dvd: Chapter Break Cell Found
[11:53:48] MPEG2: Chapter Break Cell Found, searching for GOP
[11:53:48] MPEG2: Group of pictures found, searching for I-Frame
[11:53:48] MPEG2: I-Frame Found
[11:53:48] MPEG2: Chapter Break Inserted
[11:53:48] work Renderer: Copying Chapter Break @ 116880628
[11:55:34] dvd: End of Cell (6) at block 532372
[11:55:34] dvd: Beginning of Cell (7) at block 532524
[11:55:34] dvd: Chapter Break Cell Found
[11:55:37] MPEG2: Chapter Break Cell Found, searching for GOP
[11:55:37] MPEG2: Group of pictures found, searching for I-Frame
[11:55:37] MPEG2: I-Frame Found
[11:55:37] MPEG2: Chapter Break Inserted
[11:55:38] sync: audio 0 time went backwards 18 ms, dropped 1 frames (frame 139217547, expected 139219200)
[11:55:38] work Renderer: Copying Chapter Break @ 139238940
[11:56:57] dvd: End of Cell (7) at block 590674
[11:56:57] dvd: Beginning of Cell (8) at block 591073
[11:56:57] dvd: Chapter Break Cell Found
[11:57:00] MPEG2: Chapter Break Cell Found, searching for GOP
[11:57:00] MPEG2: Group of pictures found, searching for I-Frame
[11:57:00] MPEG2: I-Frame Found
[11:57:00] MPEG2: Chapter Break Inserted
[11:57:00] work Renderer: Copying Chapter Break @ 155304454
[11:58:40] dvd: End of Cell (8) at block 660298
[11:58:41] dvd: Beginning of Cell (9) at block 660435
[11:58:41] dvd: Chapter Break Cell Found
[11:58:44] MPEG2: Chapter Break Cell Found, searching for GOP
[11:58:44] MPEG2: Group of pictures found, searching for I-Frame
[11:58:44] MPEG2: I-Frame Found
[11:58:44] MPEG2: Chapter Break Inserted
[11:58:44] sync: audio 0 time went backwards 17 ms, dropped 1 frames (frame 173463689, expected 173465280)
[11:58:44] work Renderer: Copying Chapter Break @ 173485337
[11:59:54] dvd: End of Cell (9) at block 725550
[11:59:55] dvd: Beginning of Cell (10) at block 725778
[11:59:55] dvd: Chapter Break Cell Found
[11:59:57] MPEG2: Chapter Break Cell Found, searching for GOP
[11:59:57] MPEG2: Group of pictures found, searching for I-Frame
[11:59:58] sync: audio 0 time went backwards 9 ms, dropped 1 frames (frame 191389512, expected 191390400)
[11:59:58] MPEG2: I-Frame Found
[11:59:58] MPEG2: Chapter Break Inserted
[11:59:58] work Renderer: Copying Chapter Break @ 191411070
[12:01:18] dvd: End of Cell (10) at block 797878
[12:01:19] dvd: Beginning of Cell (11) at block 798150
[12:01:19] dvd: Chapter Break Cell Found
[12:01:22] MPEG2: Chapter Break Cell Found, searching for GOP
[12:01:22] MPEG2: Group of pictures found, searching for I-Frame
[12:01:22] MPEG2: I-Frame Found
[12:01:22] MPEG2: Chapter Break Inserted
[12:01:22] work Renderer: Copying Chapter Break @ 211214624
[12:02:25] dvd: End of Cell (11) at block 859598
[12:02:26] dvd: Beginning of Cell (12) at block 859792
[12:02:26] dvd: Chapter Break Cell Found
[12:02:28] MPEG2: Chapter Break Cell Found, searching for GOP
[12:02:28] MPEG2: Group of pictures found, searching for I-Frame
[12:02:28] MPEG2: I-Frame Found
[12:02:28] MPEG2: Chapter Break Inserted
[12:02:28] work Renderer: Copying Chapter Break @ 227840003
[12:03:24] dvd: End of Cell (12) at block 914709
[12:03:25] dvd: Beginning of Cell (13) at block 914923
[12:03:25] dvd: Chapter Break Cell Found
[12:03:28] sync: audio 0 time went backwards 34 ms, dropped 1 frames (frame 242524587, expected 242527680)
[12:03:28] MPEG2: Chapter Break Cell Found, searching for GOP
[12:03:28] MPEG2: Group of pictures found, searching for I-Frame
[12:03:28] MPEG2: I-Frame Found
[12:03:28] MPEG2: Chapter Break Inserted
[12:03:29] work Renderer: Copying Chapter Break @ 242547075
[12:05:11] dvd: End of Cell (13) at block 1003891
[12:05:11] dvd: Beginning of Cell (14) at block 1004043
[12:05:11] dvd: Chapter Break Cell Found
[12:05:14] MPEG2: Chapter Break Cell Found, searching for GOP
[12:05:14] MPEG2: Group of pictures found, searching for I-Frame
[12:05:14] MPEG2: I-Frame Found
[12:05:14] MPEG2: Chapter Break Inserted
[12:05:14] work Renderer: Copying Chapter Break @ 266959326
[12:05:14] sync: audio 0 time went backwards 26 ms, dropped 1 frames (frame 266933328, expected 266935680)
[12:07:29] dvd: End of Cell (14) at block 1112749
[12:07:29] dvd: Beginning of Cell (15) at block 1113090
[12:07:29] dvd: Chapter Break Cell Found
[12:07:33] MPEG2: Chapter Break Cell Found, searching for GOP
[12:07:33] MPEG2: Group of pictures found, searching for I-Frame
[12:07:33] MPEG2: I-Frame Found
[12:07:33] MPEG2: Chapter Break Inserted
[12:07:33] work Renderer: Copying Chapter Break @ 296769356
[12:10:56] dvd: End of Cell (15) at block 1247511
[12:10:56] dvd: Beginning of Cell (16) at block 1247746
[12:10:56] dvd: Chapter Break Cell Found
[12:10:59] MPEG2: Chapter Break Cell Found, searching for GOP
[12:10:59] MPEG2: Group of pictures found, searching for I-Frame
[12:10:59] MPEG2: I-Frame Found
[12:10:59] MPEG2: Chapter Break Inserted
[12:10:59] sync: video time went backwards 19 ms, dropped 1 frames (frame 333401225, expected 333402950)
[12:10:59] sync: audio 0 time went backwards 55 ms, dropped 2 frames (frame 333375206, expected 333380160)
[12:14:08] dvd: End of Cell (16) at block 1400239
[12:14:08] dvd: Beginning of Cell (17) at block 1400367
[12:14:08] dvd: Chapter Break Cell Found
[12:14:11] MPEG2: Chapter Break Cell Found, searching for GOP
[12:14:11] MPEG2: Group of pictures found, searching for I-Frame
[12:14:11] MPEG2: I-Frame Found
[12:14:11] MPEG2: Chapter Break Inserted
[12:14:11] work Renderer: Copying Chapter Break @ 374688318
[12:14:11] sync: audio 0 time went backwards 37 ms, dropped 1 frames (frame 374667369, expected 374670720)
[12:17:03] dvd: End of Cell (17) at block 1548221
[12:17:04] dvd: Beginning of Cell (18) at block 1548368
[12:17:04] dvd: Chapter Break Cell Found
[12:17:06] MPEG2: Chapter Break Cell Found, searching for GOP
[12:17:06] MPEG2: Group of pictures found, searching for I-Frame
[12:17:06] MPEG2: I-Frame Found
[12:17:06] MPEG2: Chapter Break Inserted
[12:17:07] sync: audio 0 time went backwards 38 ms, dropped 2 frames (frame 415548899, expected 415552320)
[12:17:07] work Renderer: Copying Chapter Break @ 415571213
[12:17:32] dvd: End of Cell (18) at block 1572354
[12:17:32] dvd: Beginning of Cell (19) at block 1572637
[12:17:32] dvd: Chapter Break Cell Found
[12:17:35] MPEG2: Chapter Break Cell Found, searching for GOP
[12:17:35] MPEG2: Group of pictures found, searching for I-Frame
[12:17:35] MPEG2: I-Frame Found
[12:17:35] MPEG2: Chapter Break Inserted
[12:17:36] work Renderer: Copying Chapter Break @ 421781942
[12:17:57] dvd: End of Cell (19) at block 1594452
[12:17:58] dvd: Beginning of Cell (20) at block 1594829
[12:17:58] dvd: Chapter Break Cell Found
[12:18:01] MPEG2: Chapter Break Cell Found, searching for GOP
[12:18:01] MPEG2: Group of pictures found, searching for I-Frame
[12:18:01] MPEG2: I-Frame Found
[12:18:01] MPEG2: Chapter Break Inserted
[12:18:01] sync: audio 0 time went backwards 17 ms, dropped 1 frames (frame 427813811, expected 427815360)
[12:18:01] work Renderer: Copying Chapter Break @ 427834580
[12:21:36] dvd: End of Cell (20) at block 1784761
[12:21:36] dvd: Beginning of Cell (21) at block 1784836
[12:21:36] dvd: Chapter Break Cell Found
[12:21:39] MPEG2: Chapter Break Cell Found, searching for GOP
[12:21:39] MPEG2: Group of pictures found, searching for I-Frame
[12:21:39] MPEG2: I-Frame Found
[12:21:39] MPEG2: Chapter Break Inserted
[12:21:39] sync: audio 0 time went backwards 8 ms, dropped 1 frames (frame 480423516, expected 480424320)
[12:21:40] work Renderer: Copying Chapter Break @ 480445005
[12:22:39] dvd: End of Cell (21) at block 1837970
[12:22:39] dvd: Beginning of Cell (22) at block 1838069
[12:22:39] dvd: Chapter Break Cell Found
[12:22:42] MPEG2: Chapter Break Cell Found, searching for GOP
[12:22:42] MPEG2: Group of pictures found, searching for I-Frame
[12:22:42] MPEG2: I-Frame Found
[12:22:42] MPEG2: Chapter Break Inserted
[12:22:42] work Renderer: Copying Chapter Break @ 494903375
[12:26:59] dvd: End of Cell (22) at block 2025071
[12:27:00] dvd: Beginning of Cell (23) at block 2025398
[12:27:00] dvd: Chapter Break Cell Found
[12:27:03] sync: audio 0 time went backwards 15 ms, dropped 1 frames (frame 546556387, expected 546557760)
[12:27:03] MPEG2: Chapter Break Cell Found, searching for GOP
[12:27:03] MPEG2: Group of pictures found, searching for I-Frame
[12:27:04] MPEG2: I-Frame Found
[12:27:04] MPEG2: Chapter Break Inserted
[12:27:04] work Renderer: Copying Chapter Break @ 546587135
[12:29:16] dvd: End of Cell (23) at block 2111737
[12:29:17] dvd: Beginning of Cell (24) at block 2111918
[12:29:17] dvd: Chapter Break Cell Found
[12:29:19] MPEG2: Chapter Break Cell Found, searching for GOP
[12:29:19] MPEG2: Group of pictures found, searching for I-Frame
[12:29:19] MPEG2: I-Frame Found
[12:29:19] MPEG2: Chapter Break Inserted
[12:29:20] work Renderer: Copying Chapter Break @ 569679475
[12:30:12] 6436.361816s: Film -> Video
[12:30:13] 6436.545410s: Video -> Film
[12:31:43] dvd: End of Cell (24) at block 2216398
[12:31:43] dvd: Beginning of Cell (25) at block 2216636
[12:31:43] dvd: Chapter Break Cell Found
[12:31:47] MPEG2: Chapter Break Cell Found, searching for GOP
[12:31:47] MPEG2: Group of pictures found, searching for I-Frame
[12:31:47] MPEG2: I-Frame Found
[12:31:47] MPEG2: Chapter Break Inserted
[12:31:47] work Renderer: Copying Chapter Break @ 598325739
[12:32:13] dvd: End of Cell (25) at block 2240186
[12:32:13] dvd: Beginning of Cell (26) at block 2240384
[12:32:13] dvd: Chapter Break Cell Found
[12:32:16] MPEG2: Chapter Break Cell Found, searching for GOP
[12:32:16] MPEG2: Group of pictures found, searching for I-Frame
[12:32:16] MPEG2: I-Frame Found
[12:32:16] MPEG2: Chapter Break Inserted
[12:32:17] sync: audio 0 time went backwards 8 ms, dropped 1 frames (frame 604839511, expected 604840320)
[12:32:17] work Renderer: Copying Chapter Break @ 604856533
[12:32:59] dvd: End of Cell (26) at block 2277971
[12:32:59] dvd: Beginning of Cell (27) at block 2278149
[12:32:59] dvd: Chapter Break Cell Found
[12:33:02] MPEG2: Chapter Break Cell Found, searching for GOP
[12:33:02] MPEG2: Group of pictures found, searching for I-Frame
[12:33:02] MPEG2: I-Frame Found
[12:33:02] MPEG2: Chapter Break Inserted
[12:33:02] work Renderer: Copying Chapter Break @ 614990729
[12:33:34] dvd: End of Cell (27) at block 2304912
[12:33:34] dvd: Beginning of Cell (28) at block 2305150
[12:33:34] dvd: Chapter Break Cell Found
[12:33:37] MPEG2: Chapter Break Cell Found, searching for GOP
[12:33:37] MPEG2: Group of pictures found, searching for I-Frame
[12:33:37] MPEG2: I-Frame Found
[12:33:37] MPEG2: Chapter Break Inserted
[12:33:38] work Renderer: Copying Chapter Break @ 622254669
[12:35:02] dvd: End of Cell (28) at block 2396331
[12:35:02] dvd: Beginning of Cell (29) at block 2396366
[12:35:02] dvd: Chapter Break Cell Found
[12:35:02] dvd: End of Cell (29) at block 2396366
[12:35:02] reader: done. 29 scr changes
[12:35:02] thread f0185000 exited ("reader")
[12:35:04] MPEG2: Chapter Break Cell Found, searching for GOP
[12:35:04] MPEG2: Group of pictures found, searching for I-Frame
[12:35:04] MPEG2: I-Frame Found
[12:35:04] MPEG2: Chapter Break Inserted
[12:35:04] sync: got 173887 frames, 173730 expected
[12:35:05] sync: reader has exited, adding silence to audio 0
[12:35:05] sync: added 32 ms of silence to audio 0
[12:35:05] thread f0491000 exited ("AAC encoder (libfaac)")
[12:35:05] thread f030b000 exited ("Renderer")
[12:35:05] thread f038d000 exited ("H.264/AVC encoder (libx264)")
[12:35:05] thread f0289000 exited ("MPEG-2 decoder (libmpeg2)")
[12:35:05] thread f040f000 exited ("AC3 decoder")
[12:35:05] thread f0289000 joined ("MPEG-2 decoder (libmpeg2)")
[12:35:05] thread f030b000 joined ("Renderer")
[12:35:05] render: lost time: 0 (0 frames)
[12:35:05] render: gained time: 0 (0 frames) (0 not accounted for)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] thread f038d000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:3464 Avg QP:16.67 size: 8684 PSNR Mean Y:46.28 U:48.93 V:49.19 Avg:46.90 Global:45.75
x264 [info]: slice P:170423 Avg QP:20.58 size: 2300 PSNR Mean Y:42.29 U:45.29 V:45.30 Avg:43.01 Global:42.22
x264 [info]: mb I I16..4: 21.2% 0.0% 78.8%
x264 [info]: mb P I16..4: 3.4% 0.0% 6.7% P16..4: 39.5% 14.1% 8.1% 2.4% 1.8% skip:24.0%
x264 [info]: ref P 78.2% 10.9% 6.9% 4.0%
x264 [info]: SSIM Mean Y:0.9855604
x264 [info]: PSNR Mean Y:42.373 U:45.364 V:45.373 Avg:43.084 Global:42.269 kb/s:465.59
[12:35:05] thread f040f000 joined ("AC3 decoder")
[12:35:05] thread f0491000 joined ("AAC encoder (libfaac)")
[12:35:05] thread f0185000 joined ("reader")
[12:35:05] mux: file size, 541513557 bytes
[12:35:05] mux: track 0, 422089658 bytes, 465.61 kbps
[12:35:05] mux: track 1, 116006071 bytes, 127.97 kbps
[12:35:05] mux: overhead, 7.03 bytes per frame
[12:35:05] thread f0207000 exited ("muxer")
[12:35:05] thread f0207000 joined ("muxer")
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 2 buffer(s)
[12:35:05] Freed 5 buffers of size 512
[12:35:05] Freed 2 buffers of size 1024
[12:35:05] Freed 2048 buffers of size 2048
[12:35:05] Freed 0 buffers of size 4096
[12:35:05] Freed 0 buffers of size 8192
[12:35:05] Freed 35 buffers of size 16384
[12:35:05] Freed 0 buffers of size 32768
[12:35:05] Freed 66 buffers of size 518400
[12:35:05] Allocated 38986752 bytes of buffers on this pass and Freed 38986752 bytes, 0 bytes leaked
[12:35:05] thread f0103000 exited ("work")
[12:35:05] thread f0103000 joined ("work")
[12:35:05] libhb: work result = 0
Rip done!
[12:35:06] thread f0081000 exited ("libhb")
[12:35:06] thread f0081000 joined ("libhb")
HandBrake has exited.
Encoding: task 1 of 1, 4.36 % (81.12 fps, avg 67.60 fps, ETA 00h40m58s)[11:47:21] dvd: End of Cell (1) at block 114406
Encoding: task 1 of 1, 4.36 % (81.12 fps, avg 67.60 fps, ETA 00h40m58s)[11:47:22] dvd: Beginning of Cell (2) at block 114609
[11:47:22] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 4.44 % (64.36 fps, avg 67.36 fps, ETA 00h41m05s)[11:47:24] MPEG2: Chapter Break Cell Found, searching for GOP
[11:47:24] MPEG2: Group of pictures found, searching for I-Frame
[11:47:24] MPEG2: I-Frame Found
[11:47:24] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 4.45 % (54.61 fps, avg 67.23 fps, ETA 00h41m09s)[11:47:24] sync: audio 0 time went backwards 6 ms, dropped 1 frames (frame 29058596, expected 29059200)
Encoding: task 1 of 1, 4.46 % (54.61 fps, avg 67.23 fps, ETA 00h41m09s)[11:47:25] work Renderer: Copying Chapter Break @ 29088078
Encoding: task 1 of 1, 5.89 % (65.23 fps, avg 67.30 fps, ETA 00h40m29s)[11:48:01] dvd: End of Cell (2) at block 153522
Encoding: task 1 of 1, 5.91 % (65.23 fps, avg 67.30 fps, ETA 00h40m29s)[11:48:02] dvd: Beginning of Cell (3) at block 153749
[11:48:02] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 6.00 % (70.05 fps, avg 67.39 fps, ETA 00h40m24s)[11:48:04] MPEG2: Chapter Break Cell Found, searching for GOP
[11:48:04] MPEG2: Group of pictures found, searching for I-Frame
[11:48:04] MPEG2: I-Frame Found
[11:48:04] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 6.01 % (65.06 fps, avg 67.25 fps, ETA 00h40m28s)[11:48:04] work Renderer: Copying Chapter Break @ 39157961
Encoding: task 1 of 1, 9.95 % (67.50 fps, avg 68.52 fps, ETA 00h38m04s)[11:49:41] dvd: End of Cell (3) at block 253212
Encoding: task 1 of 1, 9.96 % (75.32 fps, avg 68.59 fps, ETA 00h38m01s)[11:49:41] dvd: Beginning of Cell (4) at block 253387
[11:49:41] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 10.06 % (83.90 fps, avg 68.71 fps, ETA 00h37m54s)[11:49:44] MPEG2: Chapter Break Cell Found, searching for GOP
[11:49:44] MPEG2: Group of pictures found, searching for I-Frame
Encoding: task 1 of 1, 10.07 % (83.90 fps, avg 68.71 fps, ETA 00h37m54s)[11:49:44] sync: audio 0 time went backwards 46 ms, dropped 2 frames (frame 65656949, expected 65661120)
[11:49:44] MPEG2: I-Frame Found
[11:49:44] MPEG2: Chapter Break Inserted
[11:49:44] sync: video time went backwards 30 ms, dropped 1 frames (frame 65688201, expected 65690964)
Encoding: task 1 of 1, 11.59 % (46.50 fps, avg 67.03 fps, ETA 00h38m12s)[11:50:30] dvd: End of Cell (4) at block 293245
Encoding: task 1 of 1, 11.59 % (42.96 fps, avg 66.92 fps, ETA 00h38m15s)[11:50:30] dvd: Beginning of Cell (5) at block 293374
[11:50:30] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 11.68 % (46.32 fps, avg 66.81 fps, ETA 00h38m17s)[11:50:33] MPEG2: Chapter Break Cell Found, searching for GOP
[11:50:33] MPEG2: Group of pictures found, searching for I-Frame
[11:50:33] MPEG2: I-Frame Found
[11:50:33] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 11.70 % (48.50 fps, avg 66.73 fps, ETA 00h38m19s)[11:50:34] work Renderer: Copying Chapter Break @ 76208496
Encoding: task 1 of 1, 17.80 % (71.64 fps, avg 62.39 fps, ETA 00h38m09s)[11:53:45] dvd: End of Cell (5) at block 446676
Encoding: task 1 of 1, 17.81 % (67.87 fps, avg 62.39 fps, ETA 00h38m08s)[11:53:45] dvd: Beginning of Cell (6) at block 446901
[11:53:45] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 17.91 % (63.67 fps, avg 62.40 fps, ETA 00h38m06s)[11:53:48] MPEG2: Chapter Break Cell Found, searching for GOP
[11:53:48] MPEG2: Group of pictures found, searching for I-Frame
[11:53:48] MPEG2: I-Frame Found
[11:53:48] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 17.92 % (63.67 fps, avg 62.40 fps, ETA 00h38m06s)[11:53:48] work Renderer: Copying Chapter Break @ 116880628
Encoding: task 1 of 1, 21.21 % (69.55 fps, avg 60.88 fps, ETA 00h37m29s)[11:55:34] dvd: End of Cell (6) at block 532372
[11:55:34] dvd: Beginning of Cell (7) at block 532524
[11:55:34] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 21.33 % (73.63 fps, avg 60.94 fps, ETA 00h37m23s)[11:55:37] MPEG2: Chapter Break Cell Found, searching for GOP
[11:55:37] MPEG2: Group of pictures found, searching for I-Frame
[11:55:37] MPEG2: I-Frame Found
[11:55:37] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 21.34 % (73.63 fps, avg 60.94 fps, ETA 00h37m23s)[11:55:38] sync: audio 0 time went backwards 18 ms, dropped 1 frames (frame 139217547, expected 139219200)
Encoding: task 1 of 1, 21.36 % (79.20 fps, avg 60.96 fps, ETA 00h37m21s)[11:55:38] work Renderer: Copying Chapter Break @ 139238940
Encoding: task 1 of 1, 23.71 % (56.11 fps, avg 59.84 fps, ETA 00h36m55s)[11:56:57] dvd: End of Cell (7) at block 590674
Encoding: task 1 of 1, 23.72 % (56.11 fps, avg 59.84 fps, ETA 00h36m55s)[11:56:57] dvd: Beginning of Cell (8) at block 591073
[11:56:57] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 23.80 % (59.10 fps, avg 59.87 fps, ETA 00h36m51s)[11:57:00] MPEG2: Chapter Break Cell Found, searching for GOP
[11:57:00] MPEG2: Group of pictures found, searching for I-Frame
[11:57:00] MPEG2: I-Frame Found
[11:57:00] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 23.82 % (65.66 fps, avg 59.87 fps, ETA 00h36m50s)[11:57:00] work Renderer: Copying Chapter Break @ 155304454
Encoding: task 1 of 1, 26.45 % (70.16 fps, avg 58.08 fps, ETA 00h36m41s)[11:58:40] dvd: End of Cell (8) at block 660298
Encoding: task 1 of 1, 26.46 % (66.20 fps, avg 58.09 fps, ETA 00h36m39s)[11:58:41] dvd: Beginning of Cell (9) at block 660435
[11:58:41] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 26.59 % (68.74 fps, avg 58.13 fps, ETA 00h36m34s)[11:58:44] MPEG2: Chapter Break Cell Found, searching for GOP
[11:58:44] MPEG2: Group of pictures found, searching for I-Frame
[11:58:44] MPEG2: I-Frame Found
[11:58:44] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 26.61 % (68.74 fps, avg 58.13 fps, ETA 00h36m34s)[11:58:44] sync: audio 0 time went backwards 17 ms, dropped 1 frames (frame 173463689, expected 173465280)
[11:58:44] work Renderer: Copying Chapter Break @ 173485337
Encoding: task 1 of 1, 29.23 % (72.69 fps, avg 58.68 fps, ETA 00h34m56s)[11:59:54] dvd: End of Cell (9) at block 725550
Encoding: task 1 of 1, 29.24 % (67.99 fps, avg 58.68 fps, ETA 00h34m55s)[11:59:55] dvd: Beginning of Cell (10) at block 725778
[11:59:55] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 29.35 % (69.35 fps, avg 58.71 fps, ETA 00h34m51s)[11:59:57] MPEG2: Chapter Break Cell Found, searching for GOP
[11:59:57] MPEG2: Group of pictures found, searching for I-Frame
[11:59:58] sync: audio 0 time went backwards 9 ms, dropped 1 frames (frame 191389512, expected 191390400)
Encoding: task 1 of 1, 29.35 % (69.35 fps, avg 58.71 fps, ETA 00h34m51s)[11:59:58] MPEG2: I-Frame Found
[11:59:58] MPEG2: Chapter Break Inserted
[11:59:58] work Renderer: Copying Chapter Break @ 191411070
Encoding: task 1 of 1, 32.27 % (67.60 fps, avg 59.06 fps, ETA 00h33m12s)[12:01:18] dvd: End of Cell (10) at block 797878
Encoding: task 1 of 1, 32.27 % (67.60 fps, avg 59.06 fps, ETA 00h33m12s)[12:01:19] dvd: Beginning of Cell (11) at block 798150
[12:01:19] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 32.38 % (56.06 fps, avg 59.05 fps, ETA 00h33m10s)[12:01:22] MPEG2: Chapter Break Cell Found, searching for GOP
[12:01:22] MPEG2: Group of pictures found, searching for I-Frame
[12:01:22] MPEG2: I-Frame Found
[12:01:22] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 32.39 % (54.74 fps, avg 59.05 fps, ETA 00h33m09s)[12:01:22] work Renderer: Copying Chapter Break @ 211214624
Encoding: task 1 of 1, 34.83 % (70.30 fps, avg 59.53 fps, ETA 00h31m42s)[12:02:25] dvd: End of Cell (11) at block 859598
[12:02:26] dvd: Beginning of Cell (12) at block 859792
[12:02:26] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 34.93 % (71.75 fps, avg 59.57 fps, ETA 00h31m38s)[12:02:28] MPEG2: Chapter Break Cell Found, searching for GOP
[12:02:28] MPEG2: Group of pictures found, searching for I-Frame
[12:02:28] MPEG2: I-Frame Found
[12:02:28] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 34.94 % (71.75 fps, avg 59.57 fps, ETA 00h31m38s)[12:02:28] work Renderer: Copying Chapter Break @ 227840003
Encoding: task 1 of 1, 37.06 % (58.29 fps, avg 59.88 fps, ETA 00h30m26s)[12:03:24] dvd: End of Cell (12) at block 914709
Encoding: task 1 of 1, 37.07 % (56.25 fps, avg 59.88 fps, ETA 00h30m25s)[12:03:25] dvd: Beginning of Cell (13) at block 914923
[12:03:25] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 37.19 % (56.04 fps, avg 59.87 fps, ETA 00h30m23s)[12:03:28] sync: audio 0 time went backwards 34 ms, dropped 1 frames (frame 242524587, expected 242527680)
Encoding: task 1 of 1, 37.19 % (56.04 fps, avg 59.87 fps, ETA 00h30m23s)[12:03:28] MPEG2: Chapter Break Cell Found, searching for GOP
[12:03:28] MPEG2: Group of pictures found, searching for I-Frame
[12:03:28] MPEG2: I-Frame Found
[12:03:28] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 37.20 % (56.04 fps, avg 59.87 fps, ETA 00h30m23s)[12:03:29] work Renderer: Copying Chapter Break @ 242547075
Encoding: task 1 of 1, 40.82 % (47.79 fps, avg 60.02 fps, ETA 00h28m33s)[12:05:11] dvd: End of Cell (13) at block 1003891
[12:05:11] dvd: Beginning of Cell (14) at block 1004043
[12:05:11] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 40.93 % (63.11 fps, avg 60.03 fps, ETA 00h28m30s)[12:05:14] MPEG2: Chapter Break Cell Found, searching for GOP
[12:05:14] MPEG2: Group of pictures found, searching for I-Frame
[12:05:14] MPEG2: I-Frame Found
[12:05:14] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 40.95 % (60.36 fps, avg 60.03 fps, ETA 00h28m29s)[12:05:14] work Renderer: Copying Chapter Break @ 266959326
[12:05:14] sync: audio 0 time went backwards 26 ms, dropped 1 frames (frame 266933328, expected 266935680)
Encoding: task 1 of 1, 45.39 % (46.99 fps, avg 59.75 fps, ETA 00h26m28s)[12:07:29] dvd: End of Cell (14) at block 1112749
Encoding: task 1 of 1, 45.40 % (46.99 fps, avg 59.75 fps, ETA 00h26m28s)[12:07:29] dvd: Beginning of Cell (15) at block 1113090
[12:07:29] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 45.50 % (51.75 fps, avg 59.72 fps, ETA 00h26m25s)[12:07:33] MPEG2: Chapter Break Cell Found, searching for GOP
[12:07:33] MPEG2: Group of pictures found, searching for I-Frame
[12:07:33] MPEG2: I-Frame Found
[12:07:33] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 45.51 % (51.75 fps, avg 59.72 fps, ETA 00h26m25s)[12:07:33] work Renderer: Copying Chapter Break @ 296769356
Encoding: task 1 of 1, 51.02 % (57.21 fps, avg 58.06 fps, ETA 00h24m26s)[12:10:56] dvd: End of Cell (15) at block 1247511
Encoding: task 1 of 1, 51.03 % (56.89 fps, avg 58.06 fps, ETA 00h24m25s)[12:10:56] dvd: Beginning of Cell (16) at block 1247746
[12:10:56] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 51.11 % (58.54 fps, avg 58.06 fps, ETA 00h24m23s)[12:10:59] MPEG2: Chapter Break Cell Found, searching for GOP
[12:10:59] MPEG2: Group of pictures found, searching for I-Frame
[12:10:59] MPEG2: I-Frame Found
[12:10:59] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 51.12 % (58.54 fps, avg 58.06 fps, ETA 00h24m23s)[12:10:59] sync: video time went backwards 19 ms, dropped 1 frames (frame 333401225, expected 333402950)
[12:10:59] sync: audio 0 time went backwards 55 ms, dropped 2 frames (frame 333375206, expected 333380160)
Encoding: task 1 of 1, 57.33 % (64.68 fps, avg 57.95 fps, ETA 00h21m20s)[12:14:08] dvd: End of Cell (16) at block 1400239
[12:14:08] dvd: Beginning of Cell (17) at block 1400367
[12:14:08] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 57.45 % (69.22 fps, avg 57.97 fps, ETA 00h21m16s)[12:14:11] MPEG2: Chapter Break Cell Found, searching for GOP
[12:14:11] MPEG2: Group of pictures found, searching for I-Frame
[12:14:11] MPEG2: I-Frame Found
[12:14:11] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 57.46 % (71.52 fps, avg 57.98 fps, ETA 00h21m14s)[12:14:11] work Renderer: Copying Chapter Break @ 374688318
[12:14:11] sync: audio 0 time went backwards 37 ms, dropped 1 frames (frame 374667369, expected 374670720)
Encoding: task 1 of 1, 63.59 % (55.75 fps, avg 58.32 fps, ETA 00h18m05s)[12:17:03] dvd: End of Cell (17) at block 1548221
[12:17:04] dvd: Beginning of Cell (18) at block 1548368
[12:17:04] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 63.71 % (66.18 fps, avg 58.33 fps, ETA 00h18m02s)[12:17:06] MPEG2: Chapter Break Cell Found, searching for GOP
[12:17:06] MPEG2: Group of pictures found, searching for I-Frame
[12:17:06] MPEG2: I-Frame Found
[12:17:06] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 63.72 % (77.77 fps, avg 58.35 fps, ETA 00h18m00s)[12:17:07] sync: audio 0 time went backwards 38 ms, dropped 2 frames (frame 415548899, expected 415552320)
[12:17:07] work Renderer: Copying Chapter Break @ 415571213
Encoding: task 1 of 1, 64.56 % (45.31 fps, avg 58.32 fps, ETA 00h17m36s)[12:17:32] dvd: End of Cell (18) at block 1572354
Encoding: task 1 of 1, 64.57 % (52.89 fps, avg 58.33 fps, ETA 00h17m35s)[12:17:32] dvd: Beginning of Cell (19) at block 1572637
[12:17:32] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 64.67 % (61.99 fps, avg 58.33 fps, ETA 00h17m33s)[12:17:35] MPEG2: Chapter Break Cell Found, searching for GOP
[12:17:35] MPEG2: Group of pictures found, searching for I-Frame
[12:17:35] MPEG2: I-Frame Found
[12:17:35] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 64.69 % (58.77 fps, avg 58.33 fps, ETA 00h17m32s)[12:17:36] work Renderer: Copying Chapter Break @ 421781942
Encoding: task 1 of 1, 65.48 % (65.89 fps, avg 58.39 fps, ETA 00h17m08s)[12:17:57] dvd: End of Cell (19) at block 1594452
Encoding: task 1 of 1, 65.51 % (64.29 fps, avg 58.39 fps, ETA 00h17m07s)[12:17:58] dvd: Beginning of Cell (20) at block 1594829
[12:17:58] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 65.59 % (61.81 fps, avg 58.40 fps, ETA 00h17m03s)[12:18:01] MPEG2: Chapter Break Cell Found, searching for GOP
[12:18:01] MPEG2: Group of pictures found, searching for I-Frame
[12:18:01] MPEG2: I-Frame Found
[12:18:01] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 65.60 % (61.81 fps, avg 58.40 fps, ETA 00h17m03s)[12:18:01] sync: audio 0 time went backwards 17 ms, dropped 1 frames (frame 427813811, expected 427815360)
Encoding: task 1 of 1, 65.61 % (61.81 fps, avg 58.40 fps, ETA 00h17m03s)[12:18:01] work Renderer: Copying Chapter Break @ 427834580
Encoding: task 1 of 1, 73.58 % (57.32 fps, avg 58.98 fps, ETA 00h12m59s)[12:21:36] dvd: End of Cell (20) at block 1784761
[12:21:36] dvd: Beginning of Cell (21) at block 1784836
[12:21:36] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 73.66 % (53.41 fps, avg 58.98 fps, ETA 00h12m56s)[12:21:39] MPEG2: Chapter Break Cell Found, searching for GOP
[12:21:39] MPEG2: Group of pictures found, searching for I-Frame
[12:21:39] MPEG2: I-Frame Found
[12:21:39] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 73.67 % (53.41 fps, avg 58.98 fps, ETA 00h12m56s)[12:21:39] sync: audio 0 time went backwards 8 ms, dropped 1 frames (frame 480423516, expected 480424320)
Encoding: task 1 of 1, 73.68 % (53.41 fps, avg 58.98 fps, ETA 00h12m56s)[12:21:40] work Renderer: Copying Chapter Break @ 480445005
Encoding: task 1 of 1, 75.74 % (68.90 fps, avg 59.03 fps, ETA 00h11m54s)[12:22:39] dvd: End of Cell (21) at block 1837970
[12:22:39] dvd: Beginning of Cell (22) at block 1838069
[12:22:39] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 75.88 % (61.46 fps, avg 59.03 fps, ETA 00h11m51s)[12:22:42] MPEG2: Chapter Break Cell Found, searching for GOP
[12:22:42] MPEG2: Group of pictures found, searching for I-Frame
[12:22:42] MPEG2: I-Frame Found
[12:22:42] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 75.89 % (71.38 fps, avg 59.04 fps, ETA 00h11m49s)[12:22:42] work Renderer: Copying Chapter Break @ 494903375
Encoding: task 1 of 1, 83.70 % (54.72 fps, avg 58.39 fps, ETA 00h08m05s)[12:26:59] dvd: End of Cell (22) at block 2025071
Encoding: task 1 of 1, 83.72 % (62.64 fps, avg 58.40 fps, ETA 00h08m04s)[12:27:00] dvd: Beginning of Cell (23) at block 2025398
[12:27:00] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 83.82 % (46.13 fps, avg 58.38 fps, ETA 00h08m02s)[12:27:03] sync: audio 0 time went backwards 15 ms, dropped 1 frames (frame 546556387, expected 546557760)
[12:27:03] MPEG2: Chapter Break Cell Found, searching for GOP
[12:27:03] MPEG2: Group of pictures found, searching for I-Frame
Encoding: task 1 of 1, 83.82 % (46.13 fps, avg 58.38 fps, ETA 00h08m02s)[12:27:04] MPEG2: I-Frame Found
[12:27:04] MPEG2: Chapter Break Inserted
[12:27:04] work Renderer: Copying Chapter Break @ 546587135
Encoding: task 1 of 1, 87.24 % (51.50 fps, avg 57.69 fps, ETA 00h06m24s)[12:29:16] dvd: End of Cell (23) at block 2111737
Encoding: task 1 of 1, 87.25 % (51.50 fps, avg 57.69 fps, ETA 00h06m24s)[12:29:17] dvd: Beginning of Cell (24) at block 2111918
[12:29:17] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 87.34 % (52.23 fps, avg 57.69 fps, ETA 00h06m22s)[12:29:19] MPEG2: Chapter Break Cell Found, searching for GOP
[12:29:19] MPEG2: Group of pictures found, searching for I-Frame
[12:29:19] MPEG2: I-Frame Found
[12:29:19] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 87.36 % (58.58 fps, avg 57.69 fps, ETA 00h06m21s)[12:29:20] work Renderer: Copying Chapter Break @ 569679475
Encoding: task 1 of 1, 88.82 % (49.21 fps, avg 57.51 fps, ETA 00h05m38s)[12:30:12] 6436.361816s: Film -> Video
[12:30:13] 6436.545410s: Video -> Film
Encoding: task 1 of 1, 91.58 % (52.60 fps, avg 57.36 fps, ETA 00h04m15s)[12:31:43] dvd: End of Cell (24) at block 2216398
Encoding: task 1 of 1, 91.60 % (52.60 fps, avg 57.36 fps, ETA 00h04m15s)[12:31:43] dvd: Beginning of Cell (25) at block 2216636
[12:31:43] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 91.74 % (75.03 fps, avg 57.38 fps, ETA 00h04m10s)[12:31:47] MPEG2: Chapter Break Cell Found, searching for GOP
[12:31:47] MPEG2: Group of pictures found, searching for I-Frame
[12:31:47] MPEG2: I-Frame Found
[12:31:47] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 91.76 % (75.03 fps, avg 57.38 fps, ETA 00h04m10s)[12:31:47] work Renderer: Copying Chapter Break @ 598325739
Encoding: task 1 of 1, 92.65 % (49.18 fps, avg 57.41 fps, ETA 00h03m43s)[12:32:13] dvd: End of Cell (25) at block 2240186
Encoding: task 1 of 1, 92.66 % (52.41 fps, avg 57.41 fps, ETA 00h03m42s)[12:32:13] dvd: Beginning of Cell (26) at block 2240384
[12:32:13] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 92.74 % (48.65 fps, avg 57.40 fps, ETA 00h03m39s)[12:32:16] MPEG2: Chapter Break Cell Found, searching for GOP
[12:32:16] MPEG2: Group of pictures found, searching for I-Frame
[12:32:16] MPEG2: I-Frame Found
[12:32:16] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 92.75 % (48.65 fps, avg 57.40 fps, ETA 00h03m39s)[12:32:17] sync: audio 0 time went backwards 8 ms, dropped 1 frames (frame 604839511, expected 604840320)
Encoding: task 1 of 1, 92.76 % (48.65 fps, avg 57.40 fps, ETA 00h03m39s)[12:32:17] work Renderer: Copying Chapter Break @ 604856533
Encoding: task 1 of 1, 94.17 % (60.95 fps, avg 57.42 fps, ETA 00h02m57s)[12:32:59] dvd: End of Cell (26) at block 2277971
Encoding: task 1 of 1, 94.19 % (63.37 fps, avg 57.42 fps, ETA 00h02m56s)[12:32:59] dvd: Beginning of Cell (27) at block 2278149
[12:32:59] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 94.30 % (63.09 fps, avg 57.43 fps, ETA 00h02m52s)[12:33:02] MPEG2: Chapter Break Cell Found, searching for GOP
[12:33:02] MPEG2: Group of pictures found, searching for I-Frame
[12:33:02] MPEG2: I-Frame Found
[12:33:02] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 94.32 % (63.09 fps, avg 57.43 fps, ETA 00h02m52s)[12:33:02] work Renderer: Copying Chapter Break @ 614990729
Encoding: task 1 of 1, 95.33 % (43.91 fps, avg 57.41 fps, ETA 00h02m21s)[12:33:34] dvd: End of Cell (27) at block 2304912
Encoding: task 1 of 1, 95.34 % (43.91 fps, avg 57.41 fps, ETA 00h02m21s)[12:33:34] dvd: Beginning of Cell (28) at block 2305150
[12:33:34] dvd: Chapter Break Cell Found
Encoding: task 1 of 1, 95.41 % (42.42 fps, avg 57.40 fps, ETA 00h02m19s)[12:33:37] MPEG2: Chapter Break Cell Found, searching for GOP
[12:33:37] MPEG2: Group of pictures found, searching for I-Frame
[12:33:37] MPEG2: I-Frame Found
[12:33:37] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 95.42 % (41.03 fps, avg 57.39 fps, ETA 00h02m18s)[12:33:38] work Renderer: Copying Chapter Break @ 622254669
Encoding: task 1 of 1, 99.89 % (105.04 fps, avg 58.38 fps, ETA 00h00m03s)[12:35:02] dvd: End of Cell (28) at block 2396331
[12:35:02] dvd: Beginning of Cell (29) at block 2396366
[12:35:02] dvd: Chapter Break Cell Found
[12:35:02] dvd: End of Cell (29) at block 2396366
[12:35:02] reader: done. 29 scr changes
[12:35:02] thread f0185000 exited ("reader")
Encoding: task 1 of 1, 100.00 % (118.06 fps, avg 58.42 fps, ETA 596523h14m07s)[12:35:04] MPEG2: Chapter Break Cell Found, searching for GOP
[12:35:04] MPEG2: Group of pictures found, searching for I-Frame
[12:35:04] MPEG2: I-Frame Found
[12:35:04] MPEG2: Chapter Break Inserted
Encoding: task 1 of 1, 100.00 % (118.06 fps, avg 58.42 fps, ETA 596523h14m07s)[12:35:04] sync: got 173887 frames, 173730 expected
Encoding: task 1 of 1, 100.00 % (118.06 fps, avg 58.42 fps, ETA 596523h14m07s)[12:35:05] sync: reader has exited, adding silence to audio 0
[12:35:05] sync: added 32 ms of silence to audio 0
[12:35:05] thread f0491000 exited ("AAC encoder (libfaac)")
[12:35:05] thread f030b000 exited ("Renderer")
[12:35:05] thread f038d000 exited ("H.264/AVC encoder (libx264)")
[12:35:05] thread f0289000 exited ("MPEG-2 decoder (libmpeg2)")
[12:35:05] thread f040f000 exited ("AC3 decoder")
[12:35:05] thread f0289000 joined ("MPEG-2 decoder (libmpeg2)")
[12:35:05] thread f030b000 joined ("Renderer")
[12:35:05] render: lost time: 0 (0 frames)
[12:35:05] render: gained time: 0 (0 frames) (0 not accounted for)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] thread f038d000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:3464 Avg QP:16.67 size: 8684 PSNR Mean Y:46.28 U:48.93 V:49.19 Avg:46.90 Global:45.75
x264 [info]: slice P:170423 Avg QP:20.58 size: 2300 PSNR Mean Y:42.29 U:45.29 V:45.30 Avg:43.01 Global:42.22
x264 [info]: mb I I16..4: 21.2% 0.0% 78.8%
x264 [info]: mb P I16..4: 3.4% 0.0% 6.7% P16..4: 39.5% 14.1% 8.1% 2.4% 1.8% skip:24.0%
x264 [info]: ref P 78.2% 10.9% 6.9% 4.0%
x264 [info]: SSIM Mean Y:0.9855604
x264 [info]: PSNR Mean Y:42.373 U:45.364 V:45.373 Avg:43.084 Global:42.269 kb/s:465.59
[12:35:05] thread f040f000 joined ("AC3 decoder")
[12:35:05] thread f0491000 joined ("AAC encoder (libfaac)")
[12:35:05] thread f0185000 joined ("reader")
Muxing: this may take awhile...[12:35:05] mux: file size, 541513557 bytes
[12:35:05] mux: track 0, 422089658 bytes, 465.61 kbps
[12:35:05] mux: track 1, 116006071 bytes, 127.97 kbps
[12:35:05] mux: overhead, 7.03 bytes per frame
[12:35:05] thread f0207000 exited ("muxer")
[12:35:05] thread f0207000 joined ("muxer")
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 0 buffer(s)
[12:35:05] fifo_close: trashing 2 buffer(s)
[12:35:05] Freed 5 buffers of size 512
[12:35:05] Freed 2 buffers of size 1024
[12:35:05] Freed 2048 buffers of size 2048
[12:35:05] Freed 0 buffers of size 4096
[12:35:05] Freed 0 buffers of size 8192
[12:35:05] Freed 35 buffers of size 16384
[12:35:05] Freed 0 buffers of size 32768
[12:35:05] Freed 66 buffers of size 518400
[12:35:05] Allocated 38986752 bytes of buffers on this pass and Freed 38986752 bytes, 0 bytes leaked
[12:35:05] thread f0103000 exited ("work")
[12:35:05] thread f0103000 joined ("work")
[12:35:05] libhb: work result = 0
Rip done!
[12:35:06] thread f0081000 exited ("libhb")
[12:35:06] thread f0081000 joined ("libhb")
HandBrake has exited.
real 49m38.577s
user 77m24.384s
sys 3m35.423s
/Users/dani/tmp/pineapplecli/workingcopies/vanilla/./HandBrakeCLI -i /Volumes/elaine/Hot Fuzz/VIDEO_TS -o /Users/dani/Movies/Hot Fuzz (nano).m4v -v -m -c 1-56 -P -X 320 -I -e x264 -q 0.6125 -x ref=4:direct=auto:cabac=0:analyse=all:level=30:vbv-maxrate=3000:vbv-bufsize=1700
(END)