[14:32:32] starting job
[14:32:32]  + device /Volumes/Multimedia Disk 2/DVD/Encode/GOOD_SHEPHERD
[14:32:32]  + title 1, chapter(s) 1 to 21
[14:32:32]  + 720x576 -> 720x432, crop 74/74/0/0
[14:32:32]  + video frame rate: 25.000 fps
[14:32:32]  + video quality 0.65
[14:32:32]  + PixelRatio: 2, width:720, height: 432
[14:32:32]  + encoder x264
[14:32:32]  + subtitle 20bd, English
[14:32:32]  + subtitle 24bd, English
[14:32:32] thread b00c5000 started ("reader")
[14:32:32]  + output: /Users/egroenen/Movies/GOOD_SHEPHERD-1.m4v
[14:32:32] thread b066c000 started ("muxer")
[14:32:32] thread b06ff000 started ("MPEG-2 decoder (libmpeg2)")
[14:32:32] thread b0781000 started ("Renderer")
[14:32:32] encx264: encoding with stored aspect 1033/720
[14:32:32] encx264: Encoding at constant RF 17.850002
[14:32:32] encx264: opening libx264 (pass -1)
x264 [info]: using SAR=1033/720
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 SSE3 SSSE3 Cache64 
[14:32:32] thread b0803000 started ("H.264/AVC encoder (libx264)")
[14:32:32] thread b0885000 started ("Subtitle decoder")
[14:32:32] thread b0a84000 started ("Subtitle decoder")
[14:32:32] sync: expecting 240879 video frames
[14:32:33] reader: first SCR 146 scr_offset 25854
[14:32:33] macgui: Using preset: Eds CRF 65 4900/2700
[14:34:06] dvd: end of cell 21 at block 3607096
[14:34:06] reader: done. 1 scr changes
[14:34:06] thread b00c5000 exited ("reader")
[14:34:06] sync: got 0 frames, 240879 expected
[14:34:06] thread b0803000 exited ("H.264/AVC encoder (libx264)")
[14:34:06] thread b06ff000 exited ("MPEG-2 decoder (libmpeg2)")
[14:34:06] thread b06ff000 joined ("MPEG-2 decoder (libmpeg2)")
[14:34:06] mpeg2 done: 0 frames
[14:34:06] thread b0a84000 exited ("Subtitle decoder")
[14:34:06] thread b0885000 exited ("Subtitle decoder")
[14:34:06] thread b0781000 exited ("Renderer")
[14:34:06] thread b0781000 joined ("Renderer")
[14:34:06] render: lost time: 0 (0 frames)
[14:34:06] render: gained time: 0 (0 frames) (0 not accounted for)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] thread b0803000 joined ("H.264/AVC encoder (libx264)")
[14:34:06] thread b0885000 joined ("Subtitle decoder")
[14:34:06] thread b0a84000 joined ("Subtitle decoder")
[14:34:06] thread b00c5000 joined ("reader")
[14:34:06] thread b066c000 exited ("muxer")
[14:34:06] thread b066c000 joined ("muxer")
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 1 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] fifo_close: trashing 0 buffer(s)
[14:34:06] Subtitle stream 0x20bd 'English': 3477 hits (0 forced)
[14:34:06] Subtitle stream 0x24bd 'English': 74 hits (0 forced)
[14:34:06] Found a subtitle candidate id 0x24bd
[14:34:06] Freed 1 buffers of size 512
[14:34:06] Freed 1 buffers of size 1024
[14:34:06] Freed 8 buffers of size 2048
[14:34:06] Freed 0 buffers of size 4096
[14:34:06] Freed 0 buffers of size 8192
[14:34:06] Freed 0 buffers of size 16384
[14:34:06] Freed 0 buffers of size 32768
[14:34:06] Freed 0 buffers of size 622080
[14:34:06] Allocated 17920 bytes of buffers on this pass and Freed 17920 bytes, 0 bytes leaked
[14:34:06] starting job
[14:34:06]  + device /Volumes/Multimedia Disk 2/DVD/Encode/GOOD_SHEPHERD
[14:34:06]  + title 1, chapter(s) 1 to 21
[14:34:06]  + 720x576 -> 720x432, crop 74/74/0/0
[14:34:06]  + video frame rate: 25.000 fps
[14:34:06]  + video quality 0.65
[14:34:06]  + PixelRatio: 2, width:720, height: 432
[14:34:06]  + encoder x264
[14:34:06]    + x264 options: bframes=3:ref=3:subq=5:me=umh:mixed-refs=1:no-fast-pskip=1:weightb=1:bime=1:direct=auto:vbv-maxrate=4900:vbv-bufsize=2700:cabac=0
[14:34:06]  + subtitle 24bd, English
[14:34:06]  + audio track 0
[14:34:06]    + input track 0
[14:34:06]    + audio 128 kbps, 48000 Hz
[14:34:06]    + encoder faac
[14:34:06]      + 80bd, English (AC3) (5.1 ch)
[14:34:06]        + Requested mixdown: Dolby Surround (HB_AMIXDOWN_DOLBY)
[14:34:06]        + Actual mixdown: Dolby Surround (HB_AMIXDOWN_DOLBY)
[14:34:06]  + audio track 1
[14:34:06]    + input track 0
[14:34:06]    + AC3 passthrough
[14:34:06]      + 80bd, English (AC3) (5.1 ch)
[14:34:06] thread b066c000 started ("reader")
[14:34:06]  + output: /Users/egroenen/Movies/GOOD_SHEPHERD-1.m4v
[14:34:06] thread b06ff000 started ("muxer")
[14:34:06] thread b0781000 started ("MPEG-2 decoder (libmpeg2)")
[14:34:06] thread b0803000 started ("Renderer")
[14:34:06] encx264: encoding with stored aspect 1033/720
[14:34:06] encx264: Encoding at constant RF 17.850002
[14:34:06] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=1033/720
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 SSE3 SSSE3 Cache64 
[14:34:06] thread b0885000 started ("H.264/AVC encoder (libx264)")
[14:34:06] thread b0a84000 started ("Subtitle decoder")
No accelerated IMDCT transform found
[14:34:06] thread b0b06000 started ("AC3 decoder")
[14:34:06] thread b0b88000 started ("AAC encoder (libfaac)")
No accelerated IMDCT transform found
[14:34:06] thread b0c0a000 started ("AC3 decoder")
[14:34:06] sync: expecting 240879 video frames
[14:34:07] reader: first SCR 146 scr_offset 25854
[14:34:07] mpeg2: "Chapter  1" (1) at frame 0 time 0
[14:34:07] 0.040000s: Film -> Video
[14:34:07] Using 64-bit MP4 formatting.
[14:34:07] Disabled extra audio track 2
[14:34:07] macgui: Using preset: Eds CRF 65 4900/2700
[14:36:38] mpeg2: "Chapter  2" (2) at frame 7732 time 27835200
x264 [warning]: VBV underflow (-21528 bits)
x264 [warning]: VBV underflow (-13376 bits)
x264 [warning]: VBV underflow (-72168 bits)
x264 [warning]: VBV underflow (-4904 bits)
x264 [warning]: VBV underflow (-44688 bits)
x264 [warning]: VBV underflow (-47688 bits)
[14:40:35] mpeg2: "Chapter  3" (3) at frame 18229 time 65624400
[14:45:05] mpeg2: "Chapter  4" (4) at frame 31897 time 114829200
[14:50:09] mpeg2: "Chapter  5" (5) at frame 46804 time 168494400
[14:53:16] mpeg2: "Chapter  6" (6) at frame 55998 time 201592800
x264 [warning]: VBV underflow (-99216 bits)
x264 [warning]: VBV underflow (-34656 bits)
[14:56:48] mpeg2: "Chapter  7" (7) at frame 64586 time 232509600
[15:00:39] mpeg2: "Chapter  8" (8) at frame 76300 time 274680000
[15:05:01] mpeg2: "Chapter  9" (9) at frame 90038 time 324136800
[15:10:23] mpeg2: "Chapter 10" (10) at frame 108064 time 389030400
[15:14:42] mpeg2: "Chapter 11" (11) at frame 121846 time 438645600
[15:20:10] mpeg2: "Chapter 12" (12) at frame 138552 time 498787200
[15:22:24] mpeg2: "Chapter 13" (13) at frame 146089 time 525920400
[15:26:09] mpeg2: "Chapter 14" (14) at frame 158213 time 569566800
[15:31:11] mpeg2: "Chapter 15" (15) at frame 174778 time 629200800
[15:35:00] mpeg2: "Chapter 16" (16) at frame 186923 time 672922800
[15:39:44] mpeg2: "Chapter 17" (17) at frame 200276 time 720993600
[15:42:06] mpeg2: "Chapter 18" (18) at frame 207620 time 747432000
[15:47:16] mpeg2: "Chapter 19" (19) at frame 222239 time 800060400
[15:49:48] mpeg2: "Chapter 20" (20) at frame 229375 time 825750000
[15:51:45] dvd: end of cell 21 at block 3607096
[15:51:45] reader: done. 1 scr changes
[15:51:45] thread b066c000 exited ("reader")
[15:51:47] mpeg2: "Chapter 21" (21) at frame 240839 time 867040217
[15:51:47] 9633.780273s: Video -> Film
[15:51:47] sync: reader has exited, adding silence to audio 1
[15:51:47] sync: reader has exited, adding silence to audio 0
[15:51:48] sync: got 240840 frames, 240879 expected
[15:51:48] sync: added 288 ms of silence to audio 0
[15:51:48] sync: added 288 ms of silence to audio 1
[15:51:48] thread b0c0a000 exited ("AC3 decoder")
[15:51:48] thread b0b06000 exited ("AC3 decoder")
[15:51:48] thread b0a84000 exited ("Subtitle decoder")
[15:51:48] thread b0885000 exited ("H.264/AVC encoder (libx264)")
[15:51:48] thread b0b88000 exited ("AAC encoder (libfaac)")
[15:51:48] thread b0781000 exited ("MPEG-2 decoder (libmpeg2)")
[15:51:48] thread b0803000 exited ("Renderer")
[15:51:48] thread b0781000 joined ("MPEG-2 decoder (libmpeg2)")
[15:51:48] mpeg2 done: 240840 frames
[15:51:48] thread b0803000 joined ("Renderer")
[15:51:48] render: lost time: 0 (0 frames)
[15:51:48] render: gained time: 0 (0 frames) (0 not accounted for)
[15:51:48] fifo_close: trashing 0 buffer(s)
[15:51:48] fifo_close: trashing 0 buffer(s)
[15:51:48] thread b0885000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:2468  Avg QP:15.48  size: 31007  PSNR Mean Y:47.75 U:50.48 V:50.89 Avg:48.38 Global:47.87
x264 [info]: slice P:83875 Avg QP:16.99  size: 10064  PSNR Mean Y:46.45 U:49.96 V:50.31 Avg:47.16 Global:46.40
x264 [info]: slice B:154497 Avg QP:18.62  size:  2369  PSNR Mean Y:45.86 U:49.35 V:49.63 Avg:46.63 Global:45.95
x264 [info]: mb I  I16..4: 35.1%  0.0% 64.9%
x264 [info]: mb P  I16..4: 11.8%  0.0%  7.2%  P16..4: 37.9% 22.1%  6.9%  0.0%  0.0%    skip:14.1%
x264 [info]: mb B  I16..4:  1.9%  0.0%  0.5%  B16..8: 22.4%  1.4%  1.6%  direct:13.9%  skip:58.3%
x264 [info]: direct mvs  spatial:99.4%  temporal:0.6%
x264 [info]: ref P  73.2% 16.0% 10.8%
x264 [info]: ref B  81.5% 18.5%
x264 [info]: SSIM Mean Y:0.9858195
x264 [info]: PSNR Mean Y:46.087 U:49.574 V:49.880 Avg:46.830 Global:46.123 kb/s:1068.49
[15:51:48] thread b0a84000 joined ("Subtitle decoder")
[15:51:48] thread b0b06000 joined ("AC3 decoder")
[15:51:48] thread b0b88000 joined ("AAC encoder (libfaac)")
[15:51:48] thread b0c0a000 joined ("AC3 decoder")
[15:51:48] thread b066c000 joined ("reader")
[15:51:49] mux: file size, 1907337241 bytes
[15:51:49] mux: track 0, 1286682312 bytes, 1068.47 kbps
[15:51:49] mux: track 1, 154069239 bytes, 127.94 kbps
[15:51:49] mux: track 2, 462422016 bytes, 384.00 kbps
[15:51:49] mux: overhead, 4.19 bytes per frame
[15:51:49] thread b06ff000 exited ("muxer")
[15:51:49] thread b06ff000 joined ("muxer")
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 1 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 0 buffer(s)
[15:51:49] fifo_close: trashing 1 buffer(s)
[15:51:49] Freed 6 buffers of size 512
[15:51:49] Freed 3 buffers of size 1024
[15:51:49] Freed 2048 buffers of size 2048
[15:51:49] Freed 0 buffers of size 4096
[15:51:49] Freed 0 buffers of size 8192
[15:51:49] Freed 323 buffers of size 16384
[15:51:49] Freed 0 buffers of size 32768
[15:51:49] Freed 136 buffers of size 622080