[19:39:33] starting job
[19:39:33]  + device /Users/nateut/Desktop/Video Files/EXTRAS_S2_D1
[19:39:33]  + title 2, chapter(s) 1 to 7
[19:39:33]  + 720x480 -> 704x480, crop 0/0/8/2
[19:39:33]  + filters
[19:39:33]    + Detelecine (pullup) (default settings)
[19:39:33]  + video frame rate: variable (detected 29.970 fps)
[19:39:33]  + video quality 0.64
[19:39:33]  + PixelRatio: 2, width:704, height: 480
[19:39:33]  + encoder x264
[19:39:33]    + x264 options: cabac=0:ref=2:analyse=all:me=umh:subq=6:no-fast-pskip=1:mixed-refs=1:merange=32:level=30
[19:39:33]  + audio 160 kbps, 48000 Hz
[19:39:33]  + encoder faac
[19:39:33]  + dynamic range compression: 1.510000
[19:39:33]    + 80bd, English (AC3) (Dolby Surround)
[19:39:33]      + Requested mixdown: Dolby Surround (HB_AMIXDOWN_DOLBY)
[19:39:33]      + Actual mixdown: Dolby Surround (HB_AMIXDOWN_DOLBY)
[19:39:33] thread b0475000 started ("reader")
[19:39:33]  + output: /Users/nateut/Desktop/Video Files/e1title2.m4v
[19:39:33] thread b04f7000 started ("muxer")
[19:39:33] thread b05fb000 started ("MPEG-2 decoder (libmpeg2)")
[19:39:33] thread b067d000 started ("Renderer")
[19:39:33] encx264: keyint-min: 30, keyint-max: 300
[19:39:33] encx264: encoding with stored aspect 841/704
[19:39:33] encx264: Encoding at constant RF 18.360001
[19:39:33] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=841/704
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 Cache64 
[19:39:33] thread b06ff000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[19:39:33] thread b0781000 started ("AC3 decoder")
[19:39:33] thread b08a6000 started ("AAC encoder (libfaac)")
[19:39:33] sync: expecting 52271 video frames
[19:39:34] dvd: Beginning of Cell (0) at block 0
[19:39:34] reader: first SCR 146 scr_offset 4095
[19:39:34] macgui: Using preset: WTV->CRF64 VFR LA FS
[19:39:34] About to add iPod atom
[19:47:50] dvd: End of Cell (0) at block 83215
[19:47:52] dvd: Beginning of Cell (1) at block 83574
[19:47:52] dvd: Chapter Break Cell Found
[19:48:04] MPEG2: Chapter Break Cell Found, searching for GOP
[19:48:04] MPEG2: Group of pictures found, searching for I-Frame
[19:48:04] MPEG2: I-Frame Found
[19:48:04] MPEG2: Chapter Break Inserted
[19:57:44] dvd: End of Cell (1) at block 189487
[19:57:47] dvd: Beginning of Cell (2) at block 189892
[19:57:47] dvd: Chapter Break Cell Found
[19:57:56] MPEG2: Chapter Break Cell Found, searching for GOP
[19:57:56] MPEG2: Group of pictures found, searching for I-Frame
[19:57:56] MPEG2: I-Frame Found
[19:57:56] MPEG2: Chapter Break Inserted
[20:09:37] dvd: End of Cell (2) at block 318053
[20:09:38] dvd: Beginning of Cell (3) at block 318229
[20:09:38] dvd: Chapter Break Cell Found
[20:09:50] MPEG2: Chapter Break Cell Found, searching for GOP
[20:09:50] MPEG2: Group of pictures found, searching for I-Frame
[20:09:50] MPEG2: I-Frame Found
[20:09:50] MPEG2: Chapter Break Inserted
[20:20:50] dvd: End of Cell (3) at block 436303
[20:20:52] dvd: Beginning of Cell (4) at block 436534
[20:20:52] dvd: Chapter Break Cell Found
[20:21:08] MPEG2: Chapter Break Cell Found, searching for GOP
[20:21:08] MPEG2: Group of pictures found, searching for I-Frame
[20:21:08] MPEG2: I-Frame Found
[20:21:08] MPEG2: Chapter Break Inserted
[20:32:35] dvd: End of Cell (4) at block 545002
[20:32:38] dvd: Beginning of Cell (5) at block 545386
[20:32:38] dvd: Chapter Break Cell Found
[20:32:52] MPEG2: Chapter Break Cell Found, searching for GOP
[20:32:52] MPEG2: Group of pictures found, searching for I-Frame
[20:32:52] MPEG2: I-Frame Found
[20:32:52] MPEG2: Chapter Break Inserted
[20:43:43] dvd: End of Cell (5) at block 649981
[20:43:43] dvd: Beginning of Cell (6) at block 1949234
[20:43:43] dvd: Chapter Break Cell Found
[20:43:43] dvd: End of Cell (6) at block 1949234
[20:43:43] reader: done. 1 scr changes
[20:43:43] thread b0475000 exited ("reader")
[20:44:00] MPEG2: Chapter Break Cell Found, searching for GOP
[20:44:00] MPEG2: Group of pictures found, searching for I-Frame
[20:44:00] 1744.342529s: Video -> Film
[20:44:00] MPEG2: I-Frame Found
[20:44:00] MPEG2: Chapter Break Inserted
[20:44:01] sync: got 52290 frames, 52271 expected
[20:44:02] thread b06ff000 exited ("H.264/AVC encoder (libx264)")
[20:44:02] thread b0781000 exited ("AC3 decoder")
[20:44:02] thread b067d000 exited ("Renderer")
[20:44:02] thread b05fb000 exited ("MPEG-2 decoder (libmpeg2)")
[20:44:02] thread b05fb000 joined ("MPEG-2 decoder (libmpeg2)")
[20:44:02] thread b067d000 joined ("Renderer")
[20:44:02] render: lost time: 21738717 (7239 frames)
[20:44:02] render: gained time: 21738717 (24426 frames) (0 not accounted for)
[20:44:02] render: average dropped frame duration: 3003
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 2 buffer(s)
[20:44:02] thread b06ff000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:518   Avg QP:14.44  size: 63461  PSNR Mean Y:47.89 U:48.50 V:48.65 Avg:48.09 Global:47.87
x264 [info]: slice P:44531 Avg QP:17.03  size: 13645  PSNR Mean Y:44.99 U:45.88 V:46.14 Avg:45.28 Global:44.99
x264 [info]: mb I  I16..4: 18.8%  0.0% 81.2%
x264 [info]: mb P  I16..4:  2.9%  0.0%  4.9%  P16..4: 49.2% 27.0%  6.9%  0.4%  0.1%    skip: 8.5%
x264 [info]: ref P  75.0% 25.0%
x264 [info]: SSIM Mean Y:0.9869404
x264 [info]: PSNR Mean Y:45.026 U:45.915 V:46.171 Avg:45.309 Global:45.009 kb/s:3408.97
[20:44:02] thread b08a6000 exited ("AAC encoder (libfaac)")
[20:44:02] thread b0781000 joined ("AC3 decoder")
[20:44:02] thread b08a6000 joined ("AAC encoder (libfaac)")
[20:44:02] thread b0475000 joined ("reader")
[20:44:02] mux: file size, 676216326 bytes
[20:44:02] mux: track 0, 640519241 bytes, 2936.97 kbps
[20:44:02] mux: track 1, 34840078 bytes, 159.75 kbps
[20:44:02] mux: overhead, 6.76 bytes per frame
[20:44:02] thread b04f7000 exited ("muxer")
[20:44:02] thread b04f7000 joined ("muxer")
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 0 buffer(s)
[20:44:02] fifo_close: trashing 7 buffer(s)
[20:44:02] Freed 5 buffers of size 512
[20:44:02] Freed 3 buffers of size 1024
[20:44:02] Freed 2048 buffers of size 2048
[20:44:02] Freed 0 buffers of size 4096
[20:44:02] Freed 0 buffers of size 8192
[20:44:02] Freed 36 buffers of size 16384
[20:44:02] Freed 0 buffers of size 32768
[20:44:02] Freed 62 buffers of size 518400
[20:44:02] Allocated 36930560 bytes of buffers on this pass and Freed 36930560 bytes, 0 bytes leaked
[20:44:02] thread b03d1000 exited ("work")
[20:44:02] thread b03d1000 joined ("work")
[20:44:02] libhb: work result = 0