[Invalid] [SVN2937] Performance regression

Archive of historical bug reports.
Please use the GitHub link above to report issues.
Forum rules
*******************************
Please be aware we are now using GitHub for issue tracking and feature requests.
- This section of the forum is now closed to new topics.

*******************************
Post Reply
siromega
Posts: 36
Joined: Mon Jul 02, 2007 5:06 am

[Invalid] [SVN2937] Performance regression

Post by siromega »

I'm pretty sure this is about x264 but I'd thought I'd ask here before I would even consider asking at the Doom9 forums. I don't know what, if any, changes you could make to the settings you send to x264, or patches (would this apply? should I upgrade GCC from 4.2.1?), before I bark up that tree.

So the performance decreased 8.2% in the 64b version. Performance is still about 25% faster than 0.9.3 on a C2Q.

Platform: Mac OSX 10.6.2
Movie: Juno (ripped from DVD to HDD)
Settings: Normal (no changes to the output except for the output file name)
Other apps running: Safari 4.0.4, activity monitor open to make sure nothing else is going on (no spotlight indexing, etc). HB managed to use 360-400% of the processor the entire time (Quad core 2.66Ghz)
I had ran it with 2929 a few times before I tried with 2937 and still had the same issue.

SVN 2902 Activity log:

Code: Select all

HandBrake Activity Log for /Users/Anthony/Movies/BatchEncode/JUNO-2902-64-n.m4v: 2009-11-15 08:29:06 -0800
Handbrake Version: svn2902 x86_64 (2009103001)

[08:29:06] macgui: scanning specifically for title: 1
[08:29:06] macgui: performNewQueueScan currentQueueEncodeIndex is: 0
[08:29:06] hb_scan: path=/Users/Anthony/Movies/BatchRip/JUNO, title_index=1
[08:29:06] thread 11ab63000 started ("scan")
[08:29:06] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/Anthony/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[08:29:06] scan: DVD has 32 title(s)
[08:29:06] scan: scanning title 1
[08:29:06] scan: opening IFO for VTS 1
[08:29:06] pgc_id: 1, pgn: 1: pgc: 0x10154d830
[08:29:06] scan: vts=1, ttn=1, cells=0->29, blocks=5->4, 2375496 blocks
[08:29:06] scan: duration is 01:36:00 (5760200 ms)
[08:29:06] scan: checking audio 1
[08:29:06] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[08:29:06] scan: checking audio 2
[08:29:06] scan: id=81bd, lang=Espanol (AC3), 3cc=spa ext=1
[08:29:06] scan: checking audio 3
[08:29:06] scan: id=82bd, lang=Francais (AC3), 3cc=fra ext=1
[08:29:06] scan: checking audio 4
[08:29:06] scan: id=83bd, lang=English (AC3) (Director's Commentary 1), 3cc=eng ext=3
[08:29:06] scan: checking subtitle 1
[08:29:06] scan: id=24bd, lang=English (Closed Caption), 3cc=eng
[08:29:06] scan: checking subtitle 2
[08:29:06] scan: id=25bd, lang=Espanol, 3cc=spa
[08:29:06] scan: checking subtitle 3
[08:29:06] scan: id=26bd, lang=Espanol, 3cc=spa
[08:29:06] scan: checking subtitle 4
[08:29:06] scan: id=27bd, lang=Francais, 3cc=fra
[08:29:06] scan: checking subtitle 5
[08:29:06] scan: subtitle channel is not active
[08:29:06] scan: checking subtitle 6
[08:29:06] scan: subtitle channel is not active
[08:29:06] scan: checking subtitle 7
[08:29:06] scan: subtitle channel is not active
[08:29:06] scan: checking subtitle 8
[08:29:06] scan: subtitle channel is not active
[08:29:06] scan: title 1 has 29 chapters
[08:29:06] scan: chap 1 c=0->0, b=5->119834 (119830), 249638 ms
[08:29:06] scan: chap 2 c=1->1, b=119835->184553 (64719), 167476 ms
[08:29:06] scan: chap 3 c=2->2, b=184554->236069 (51516), 130251 ms
[08:29:06] scan: chap 4 c=3->3, b=236070->297744 (61675), 158459 ms
[08:29:06] scan: chap 5 c=4->4, b=297745->356377 (58633), 140426 ms
[08:29:06] scan: chap 6 c=5->5, b=356378->415869 (59492), 149453 ms
[08:29:06] scan: chap 7 c=6->6, b=415870->485406 (69537), 169524 ms
[08:29:06] scan: chap 8 c=7->7, b=485407->568953 (83547), 204532 ms
[08:29:06] scan: chap 9 c=8->8, b=568954->665255 (96302), 250664 ms
[08:29:06] scan: chap 10 c=9->9, b=665256->798632 (133377), 329921 ms
[08:29:06] scan: chap 11 c=10->10, b=798633->921237 (122605), 302603 ms
[08:29:06] scan: chap 12 c=11->11, b=921238->1019867 (98630), 234755 ms
[08:29:06] scan: chap 13 c=12->12, b=1019868->1162271 (142404), 346862 ms
[08:29:06] scan: chap 14 c=13->13, b=1162272->1225532 (63261), 156600 ms
[08:29:06] scan: chap 15 c=14->14, b=1225533->1270270 (44738), 119397 ms
[08:29:06] scan: chap 16 c=15->15, b=1270271->1339835 (69565), 179518 ms
[08:29:06] scan: chap 17 c=16->16, b=1339836->1380163 (40328), 95342 ms
[08:29:06] scan: chap 18 c=17->17, b=1380164->1463640 (83477), 197385 ms
[08:29:06] scan: chap 19 c=18->18, b=1463641->1620953 (157313), 356681 ms
[08:29:06] scan: chap 20 c=19->19, b=1620954->1740767 (119814), 291560 ms
[08:29:06] scan: chap 21 c=20->20, b=1740768->1814252 (73485), 175501 ms
[08:29:06] scan: chap 22 c=21->21, b=1814253->1904260 (90008), 215419 ms
[08:29:06] scan: chap 23 c=22->22, b=1904261->1992287 (88027), 213581 ms
[08:29:06] scan: chap 24 c=23->24, b=1992288->2067862 (75575), 181709 ms
[08:29:06] scan: chap 25 c=25->25, b=2067863->2136399 (68537), 154296 ms
[08:29:06] scan: chap 26 c=26->26, b=2136400->2208183 (71784), 186545 ms
[08:29:06] scan: chap 27 c=27->27, b=2208184->2292491 (84308), 175635 ms
[08:29:06] scan: chap 28 c=28->28, b=2292492->2375495 (83004), 225450 ms
[08:29:06] scan: chap 29 c=29->29, b=0->4 (5), 1001 ms
[08:29:06] scan: aspect = 0
[08:29:06] scan: decoding previews for title 1
libdvdnav: Suspected RCE Region Protection!!!
libdvdnav: Suspected RCE Region Protection!!!
[08:29:06] scan: title angle(s) 1
[08:29:06] scan: preview 1
[08:29:06] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[08:29:06] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (Dolby Surround)
[08:29:06] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Francais (AC3) (Dolby Surround)
[08:29:06] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (2.0 ch)
[08:29:06] scan: preview 2
[08:29:06] scan: preview 3
[08:29:06] scan: preview 4
[08:29:06] scan: preview 5
[08:29:06] scan: preview 6
[08:29:06] Title's mostly NTSC Film, setting fps to 23.976
[08:29:06] scan: preview 7
[08:29:06] scan: preview 8
[08:29:06] scan: preview 9
[08:29:06] scan: preview 10
[08:29:06] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/8/0/0, aspect 16:9, PAR 32:27
[08:29:06] scan: title (0) job->width:720, job->height:400
[08:29:06] thread 11ab63000 exited ("scan")
[08:29:06] thread 11ab63000 joined ("scan")
[08:29:06] libhb: scan thread found 1 valid title(s)
[08:29:06] macgui: ScanDone state received from fQueueEncodeLibhb
[08:29:06] macgui: Preset: Normal (Default)
[08:29:06] macgui: processNewQueueEncode number of passes expected is: 1
[08:29:06] macgui: prepareJob exiting
[08:29:06] thread 11a89b000 started ("work")
[08:29:06] 1 job(s) to process
[08:29:06] starting job
[08:29:06] job configuration:
[08:29:06]  * source
[08:29:06]    + /Users/Anthony/Movies/BatchRip/JUNO
[08:29:06]    + title 1, chapter(s) 1 to 29
[08:29:06]  * destination
[08:29:06]    + /Users/Anthony/Movies/BatchEncode/JUNO-2902-64-n.m4v
[08:29:06]    + container: MPEG-4 (.mp4 and .m4v)
[08:29:06]      + chapter markers
[08:29:06]  * video track
[08:29:06]    + decoder: mpeg2
[08:29:06]      + bitrate 9801 kbps
[08:29:06]    + frame rate: same as source (around 23.976 fps)
[08:29:06]    + strict anamorphic
[08:29:06]      + modulus: 0
[08:29:06]      + storage dimensions: 720 * 480 -> 720 * 472, crop 0/8/0/0
[08:29:06]      + pixel aspect ratio: 32 / 27
[08:29:06]      + display dimensions: 853 * 472
[08:29:06]    + encoder: x264
[08:29:06]      + options: ref=2:bframes=2:me=umh:subq=6:mixed-refs=0:weightb=0:8x8dct=0:trellis=0
[08:29:06]      + quality: 20.00 (RF)
[08:29:06]  * audio track 0
[08:29:06]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[08:29:06]      + bitrate: 448 kbps, samplerate: 48000 Hz
[08:29:06]    + mixdown: Dolby Pro Logic II
[08:29:06]    + encoder: ca_aac
[08:29:06]      + bitrate: 160 kbps, samplerate: 48000 Hz
[08:29:06] thread 11ab63000 started ("reader")
libdvdnav: Using dvdnav version 4.1.3
[08:29:06] thread 11ae92000 started ("MPEG-2 decoder (libmpeg2)")
[08:29:06] thread 11af15000 started ("Renderer")
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/Anthony/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
[08:29:06] encx264: keyint-min: 24, keyint-max: 240
[08:29:06] encx264: encoding with stored aspect 32/27
[08:29:06] encx264: Encoding at constant RF 20.000000
[08:29:06] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.1 Cache64
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Suspected RCE Region Protection!!!
libdvdnav: Suspected RCE Region Protection!!!
x264 [info]: profile Main, level 3.0
[08:29:06] thread 11c144000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[08:29:06] thread 11c1c7000 started ("AC3 decoder")
[08:29:06] reader: first SCR 146 id 224 DTS 22254
[08:29:06] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[08:29:06] thread 11d546000 started ("AAC encoder (Apple)")
[08:29:06] thread 11d5c9000 started ("muxer")
[08:29:06] sync: expecting 138130 video frames
[08:29:06] sync: first pts is 3003
[08:29:06] muxmp4: track 0, chunk duration 15015
[08:29:06] muxmp4: track 1, chunk duration 8008
[08:30:03] mpeg2: "Chapter  2" (2) at frame 5988 time 22480458
[08:30:45] mpeg2: "Chapter  3" (3) at frame 10000 time 37540503
[08:31:16] mpeg2: "Chapter  4" (4) at frame 13128 time 49282233
[08:31:52] mpeg2: "Chapter  5" (5) at frame 16924 time 63531468
[08:32:29] mpeg2: "Chapter  6" (6) at frame 20288 time 76159083
[08:33:03] mpeg2: "Chapter  7" (7) at frame 23876 time 89627538
[08:33:44] mpeg2: "Chapter  8" (8) at frame 27945 time 104900796
[08:34:33] mpeg2: "Chapter  9" (9) at frame 32860 time 123351228
[08:35:24] mpeg2: "Chapter 10" (10) at frame 38865 time 145891746
[08:36:40] mpeg2: "Chapter 11" (11) at frame 46776 time 175588413
[08:37:56] mpeg2: "Chapter 12" (12) at frame 54033 time 202828626
[08:38:53] mpeg2: "Chapter 13" (13) at frame 59672 time 223996773
[08:40:12] mpeg2: "Chapter 14" (14) at frame 67997 time 255245991
[08:40:50] mpeg2: "Chapter 15" (15) at frame 71756 time 269357088
[08:41:14] mpeg2: "Chapter 16" (16) at frame 74624 time 280122843
[08:41:54] mpeg2: "Chapter 17" (17) at frame 78940 time 296324028
[08:42:15] mpeg2: "Chapter 18" (18) at frame 81224 time 304897593
[08:43:07] mpeg2: "Chapter 19" (19) at frame 85968 time 322705383
[08:44:33] mpeg2: "Chapter 20" (20) at frame 94528 time 354837483
[08:45:36] mpeg2: "Chapter 21" (21) at frame 101528 time 381113733
[08:46:18] mpeg2: "Chapter 22" (22) at frame 105740 time 396924528
[08:47:04] mpeg2: "Chapter 23" (23) at frame 110916 time 416353938
[08:47:48] mpeg2: "Chapter 24" (24) at frame 116048 time 435618183
[08:48:32] mpeg2: "Chapter 25" (25) at frame 120432 time 452074623
[08:49:18] mpeg2: "Chapter 26" (26) at frame 124136 time 465978513
[08:50:06] mpeg2: "Chapter 27" (27) at frame 128605 time 482753271
[08:50:52] mpeg2: "Chapter 28" (28) at frame 132820 time 498576078
[08:51:18] reader: done. 0 scr changes
[08:51:18] thread 11ab63000 exited ("reader")
[08:51:18] sync: got 138218 frames, 138130 expected
[08:51:18] work: average encoding speed for job is 103.746834 fps
[08:51:19] mux: file size, 928767792 bytes
[08:51:19] mux: track 0, 138218 frames, 812823212 bytes, 1127.96 kbps, fifo 64
[08:51:19] mux: video bitrate error, +812823212 bytes
[08:51:19] mux: track 1, 270231 frames, 112304275 bytes, 155.84 kbps, fifo 512
[08:51:19] mux: overhead, 8.91 bytes per frame
[08:51:19] thread 11d5c9000 exited ("muxer")
[08:51:19] thread 11d5c9000 joined ("muxer")
[08:51:19] thread 11c1c7000 exited ("AC3 decoder")
[08:51:19] thread 11d546000 exited ("AAC encoder (Apple)")
[08:51:19] thread 11ae92000 exited ("MPEG-2 decoder (libmpeg2)")
[08:51:19] thread 11ae92000 joined ("MPEG-2 decoder (libmpeg2)")
[08:51:19] mpeg2 done: 138219 frames
[08:51:19] thread 11af15000 exited ("Renderer")
[08:51:19] thread 11af15000 joined ("Renderer")
[08:51:19] render: lost time: 0 (0 frames)
[08:51:19] render: gained time: 0 (0 frames) (0 not accounted for)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] thread 11c144000 exited ("H.264/AVC encoder (libx264)")
[08:51:19] thread 11c144000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:1268  Avg QP:16.76  size: 58521  PSNR Mean Y:45.97 U:48.41 V:49.35 Avg:46.70 Global:46.39
x264 [info]: frame P:55431 Avg QP:20.55  size: 10644  PSNR Mean Y:43.21 U:45.45 V:46.49 Avg:43.90 Global:43.44
x264 [info]: frame B:81519 Avg QP:22.64  size:  1824  PSNR Mean Y:42.66 U:45.28 V:46.48 Avg:43.44 Global:42.98
x264 [info]: consecutive B-frames:  5.0% 22.7% 72.3%
x264 [info]: mb I  I16..4: 20.6%  0.0% 79.4%
x264 [info]: mb P  I16..4:  1.2%  0.0%  3.2%  P16..4: 50.9% 18.0% 10.6%  0.0%  0.0%    skip:16.1%
x264 [info]: mb B  I16..4:  4.3%  0.0%  0.4%  B16..8: 19.0%  1.0%  0.6%  direct: 9.5%  skip:65.3%  L0:32.0% L1:48.8% BI:19.2%
x264 [info]: coded y,uvDC,uvAC intra: 50.4% 84.0% 58.9% inter: 14.5% 23.4% 4.4%
x264 [info]: i16 v,h,dc,p: 36% 16% 26% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 27% 13% 18%  6%  7%  9%  6%  8%  6%
x264 [info]: ref P L0: 76.3% 23.7%
x264 [info]: SSIM Mean Y:0.9792845
x264 [info]: PSNR Mean Y:42.910 U:45.373 V:46.512 Avg:43.657 Global:43.178 kb/s:1128.05
[08:51:19] thread 11c1c7000 joined ("AC3 decoder")
[08:51:19] thread 11d546000 joined ("AAC encoder (Apple)")
[08:51:19] thread 11ab63000 joined ("reader")
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 1 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] fifo_close: trashing 0 buffer(s)
[08:51:19] Freed 6 buffers of size 1024
[08:51:19] Freed 32 buffers of size 2048
[08:51:19] Freed 32 buffers of size 16384
[08:51:19] Freed 32 buffers of size 524288
[08:51:19] Allocated 17897472 bytes of buffers on this pass and Freed 17373184 bytes, 524288 bytes leaked
[08:51:19] thread 11a89b000 exited ("work")
[08:51:19] thread 11a89b000 joined ("work")
[08:51:19] libhb: work result = 0
FPS: 103.746834
Filesize: 928.8MB

SVN 2937 Activity log:

Code: Select all

HandBrake Activity Log for /Users/Anthony/Movies/BatchEncode/JUNO-2937-64-n.m4v: 2009-11-15 13:10:19 -0800
Handbrake Version: svn2937 x86_64 (2009111501)

[13:10:19] macgui: Rip: Pending queue count is 0
[13:10:19] macgui: scanning specifically for title: 1
[13:10:19] macgui: performNewQueueScan currentQueueEncodeIndex is: 0
[13:10:19] hb_scan: path=/Users/Anthony/Movies/BatchRip/JUNO, title_index=1
[13:10:19] thread 119905000 started ("scan")
[13:10:19] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/Anthony/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[13:10:19] scan: DVD has 32 title(s)
[13:10:19] scan: scanning title 1
[13:10:19] scan: opening IFO for VTS 1
[13:10:19] pgc_id: 1, pgn: 1: pgc: 0x11568a750
[13:10:19] scan: vts=1, ttn=1, cells=0->29, blocks=5->4, 2375496 blocks
[13:10:19] scan: duration is 01:36:00 (5760200 ms)
[13:10:19] scan: checking audio 1
[13:10:19] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:10:19] scan: checking audio 2
[13:10:19] scan: id=81bd, lang=Espanol (AC3), 3cc=spa ext=1
[13:10:19] scan: checking audio 3
[13:10:19] scan: id=82bd, lang=Francais (AC3), 3cc=fra ext=1
[13:10:19] scan: checking audio 4
[13:10:19] scan: id=83bd, lang=English (AC3) (Director's Commentary 1), 3cc=eng ext=3
[13:10:19] scan: checking subtitle 1
[13:10:19] scan: id=24bd, lang=English (Closed Caption), 3cc=eng
[13:10:19] scan: checking subtitle 2
[13:10:19] scan: id=25bd, lang=Espanol, 3cc=spa
[13:10:19] scan: checking subtitle 3
[13:10:19] scan: id=26bd, lang=Espanol, 3cc=spa
[13:10:19] scan: checking subtitle 4
[13:10:19] scan: id=27bd, lang=Francais, 3cc=fra
[13:10:19] scan: checking subtitle 5
[13:10:19] scan: subtitle channel is not active
[13:10:19] scan: checking subtitle 6
[13:10:19] scan: subtitle channel is not active
[13:10:19] scan: checking subtitle 7
[13:10:19] scan: subtitle channel is not active
[13:10:19] scan: checking subtitle 8
[13:10:19] scan: subtitle channel is not active
[13:10:19] scan: title 1 has 29 chapters
[13:10:19] scan: chap 1 c=0->0, b=5->119834 (119830), 249638 ms
[13:10:19] scan: chap 2 c=1->1, b=119835->184553 (64719), 167476 ms
[13:10:19] scan: chap 3 c=2->2, b=184554->236069 (51516), 130251 ms
[13:10:19] scan: chap 4 c=3->3, b=236070->297744 (61675), 158459 ms
[13:10:19] scan: chap 5 c=4->4, b=297745->356377 (58633), 140426 ms
[13:10:19] scan: chap 6 c=5->5, b=356378->415869 (59492), 149453 ms
[13:10:19] scan: chap 7 c=6->6, b=415870->485406 (69537), 169524 ms
[13:10:19] scan: chap 8 c=7->7, b=485407->568953 (83547), 204532 ms
[13:10:19] scan: chap 9 c=8->8, b=568954->665255 (96302), 250664 ms
[13:10:19] scan: chap 10 c=9->9, b=665256->798632 (133377), 329921 ms
[13:10:19] scan: chap 11 c=10->10, b=798633->921237 (122605), 302603 ms
[13:10:19] scan: chap 12 c=11->11, b=921238->1019867 (98630), 234755 ms
[13:10:19] scan: chap 13 c=12->12, b=1019868->1162271 (142404), 346862 ms
[13:10:19] scan: chap 14 c=13->13, b=1162272->1225532 (63261), 156600 ms
[13:10:19] scan: chap 15 c=14->14, b=1225533->1270270 (44738), 119397 ms
[13:10:19] scan: chap 16 c=15->15, b=1270271->1339835 (69565), 179518 ms
[13:10:19] scan: chap 17 c=16->16, b=1339836->1380163 (40328), 95342 ms
[13:10:19] scan: chap 18 c=17->17, b=1380164->1463640 (83477), 197385 ms
[13:10:19] scan: chap 19 c=18->18, b=1463641->1620953 (157313), 356681 ms
[13:10:19] scan: chap 20 c=19->19, b=1620954->1740767 (119814), 291560 ms
[13:10:19] scan: chap 21 c=20->20, b=1740768->1814252 (73485), 175501 ms
[13:10:19] scan: chap 22 c=21->21, b=1814253->1904260 (90008), 215419 ms
[13:10:19] scan: chap 23 c=22->22, b=1904261->1992287 (88027), 213581 ms
[13:10:19] scan: chap 24 c=23->24, b=1992288->2067862 (75575), 181709 ms
[13:10:19] scan: chap 25 c=25->25, b=2067863->2136399 (68537), 154296 ms
[13:10:19] scan: chap 26 c=26->26, b=2136400->2208183 (71784), 186545 ms
[13:10:19] scan: chap 27 c=27->27, b=2208184->2292491 (84308), 175635 ms
[13:10:19] scan: chap 28 c=28->28, b=2292492->2375495 (83004), 225450 ms
[13:10:19] scan: chap 29 c=29->29, b=0->4 (5), 1001 ms
[13:10:19] scan: aspect = 0
[13:10:19] scan: decoding previews for title 1
libdvdnav: Suspected RCE Region Protection!!!
libdvdnav: Suspected RCE Region Protection!!!
[13:10:19] scan: title angle(s) 1
[13:10:19] scan: preview 1
[13:10:19] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:10:19] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (Dolby Surround)
[13:10:19] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Francais (AC3) (Dolby Surround)
[13:10:19] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (2.0 ch)
[13:10:19] scan: preview 2
[13:10:19] scan: preview 3
[13:10:19] scan: preview 4
[13:10:19] scan: preview 5
[13:10:19] scan: preview 6
[13:10:19] Title's mostly NTSC Film, setting fps to 23.976
[13:10:19] scan: preview 7
[13:10:19] scan: preview 8
[13:10:19] scan: preview 9
[13:10:19] scan: preview 10
[13:10:19] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/8/0/0, aspect 16:9, PAR 32:27
[13:10:19] scan: title (0) job->width:720, job->height:400
[13:10:19] thread 119905000 exited ("scan")
[13:10:19] thread 119905000 joined ("scan")
[13:10:19] libhb: scan thread found 1 valid title(s)
[13:10:19] macgui: ScanDone state received from fQueueEncodeLibhb
[13:10:19] macgui: Preset: Normal (Default)
[13:10:19] macgui: processNewQueueEncode number of passes expected is: 1
[13:10:19] macgui: prepareJob exiting
[13:10:19] thread 119905000 started ("work")
[13:10:19] 1 job(s) to process
[13:10:19] starting job
[13:10:19] job configuration:
[13:10:19]  * source
[13:10:19]    + /Users/Anthony/Movies/BatchRip/JUNO
[13:10:19]    + title 1, chapter(s) 1 to 29
[13:10:19]  * destination
[13:10:19]    + /Users/Anthony/Movies/BatchEncode/JUNO-2937-64-n.m4v
[13:10:19]    + container: MPEG-4 (.mp4 and .m4v)
[13:10:19]      + chapter markers
[13:10:19]  * video track
[13:10:19]    + decoder: mpeg2
[13:10:19]      + bitrate 9801 kbps
[13:10:19]    + frame rate: same as source (around 23.976 fps)
[13:10:19]    + strict anamorphic
[13:10:19]      + modulus: 0
[13:10:19]      + storage dimensions: 720 * 480 -> 720 * 472, crop 0/8/0/0
[13:10:19]      + pixel aspect ratio: 32 / 27
[13:10:19]      + display dimensions: 853 * 472
[13:10:19]    + encoder: x264
[13:10:19]      + options: ref=2:bframes=2:me=umh:subq=6:mixed-refs=0:weightb=0:8x8dct=0:trellis=0
[13:10:19]      + quality: 20.00 (RF)
[13:10:19]  * audio track 0
[13:10:19]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[13:10:19]      + bitrate: 448 kbps, samplerate: 48000 Hz
[13:10:19]    + mixdown: Dolby Pro Logic II
[13:10:19]    + encoder: ca_aac
[13:10:19]      + bitrate: 160 kbps, samplerate: 48000 Hz
[13:10:19] thread 119a2d000 started ("reader")
libdvdnav: Using dvdnav version 4.1.3
[13:10:19] thread 119ab0000 started ("MPEG-2 decoder (libmpeg2)")
[13:10:19] thread 119ddf000 started ("Renderer")
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:10:19] encx264: keyint-min: 24, keyint-max: 240
[13:10:19] encx264: encoding with stored aspect 32/27
[13:10:19] encx264: Encoding at constant RF 20.000000
[13:10:19] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.1 Cache64
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/Anthony/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Suspected RCE Region Protection!!!
libdvdnav: Suspected RCE Region Protection!!!
x264 [info]: profile Main, level 3.0
[13:10:19] thread 11b576000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[13:10:19] thread 11b5f9000 started ("AC3 decoder")
[13:10:19] reader: first SCR 146 id 224 DTS 22254
[13:10:19] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[13:10:19] thread 11c2f8000 started ("AAC encoder (Apple)")
[13:10:19] thread 11c37b000 started ("muxer")
[13:10:19] sync: expecting 138130 video frames
[13:10:19] sync: first pts is 3003
[13:10:19] muxmp4: track 0, chunk duration 15015
[13:10:19] muxmp4: track 1, chunk duration 8008
[13:11:21] mpeg2: "Chapter  2" (2) at frame 5988 time 22480458
[13:12:07] mpeg2: "Chapter  3" (3) at frame 10000 time 37540503
[13:12:42] mpeg2: "Chapter  4" (4) at frame 13128 time 49282233
[13:13:22] mpeg2: "Chapter  5" (5) at frame 16924 time 63531468
[13:14:02] mpeg2: "Chapter  6" (6) at frame 20288 time 76159083
[13:14:39] mpeg2: "Chapter  7" (7) at frame 23876 time 89627538
[13:15:24] mpeg2: "Chapter  8" (8) at frame 27945 time 104900796
[13:16:17] mpeg2: "Chapter  9" (9) at frame 32860 time 123351228
[13:17:13] mpeg2: "Chapter 10" (10) at frame 38865 time 145891746
[13:18:36] mpeg2: "Chapter 11" (11) at frame 46776 time 175588413
[13:19:59] mpeg2: "Chapter 12" (12) at frame 54033 time 202828626
[13:21:02] mpeg2: "Chapter 13" (13) at frame 59672 time 223996773
[13:22:28] mpeg2: "Chapter 14" (14) at frame 67997 time 255245991
[13:23:08] mpeg2: "Chapter 15" (15) at frame 71756 time 269357088
[13:23:35] mpeg2: "Chapter 16" (16) at frame 74624 time 280122843
[13:24:19] mpeg2: "Chapter 17" (17) at frame 78940 time 296324028
[13:24:41] mpeg2: "Chapter 18" (18) at frame 81224 time 304897593
[13:25:37] mpeg2: "Chapter 19" (19) at frame 85968 time 322705383
[13:27:10] mpeg2: "Chapter 20" (20) at frame 94528 time 354837483
[13:28:19] mpeg2: "Chapter 21" (21) at frame 101528 time 381113733
[13:29:04] mpeg2: "Chapter 22" (22) at frame 105740 time 396924528
[13:29:54] mpeg2: "Chapter 23" (23) at frame 110916 time 416353938
[13:30:41] mpeg2: "Chapter 24" (24) at frame 116048 time 435618183
[13:31:29] mpeg2: "Chapter 25" (25) at frame 120432 time 452074623
[13:32:20] mpeg2: "Chapter 26" (26) at frame 124136 time 465978513
[13:33:12] mpeg2: "Chapter 27" (27) at frame 128605 time 482753271
[13:34:01] mpeg2: "Chapter 28" (28) at frame 132820 time 498576078
[13:34:31] reader: done. 0 scr changes
[13:34:31] thread 119a2d000 exited ("reader")
[13:34:31] sync: got 138218 frames, 138130 expected
[13:34:31] work: average encoding speed for job is 95.151245 fps
[13:34:32] mux: file size, 908625861 bytes
[13:34:32] mux: track 0, 138218 frames, 792680185 bytes, 1100.00 kbps, fifo 64
[13:34:32] mux: video bitrate error, +792680185 bytes
[13:34:32] mux: track 1, 270231 frames, 112304275 bytes, 155.84 kbps, fifo 512
[13:34:32] mux: overhead, 8.92 bytes per frame
[13:34:32] thread 11c37b000 exited ("muxer")
[13:34:32] thread 11c37b000 joined ("muxer")
[13:34:32] thread 11c2f8000 exited ("AAC encoder (Apple)")
[13:34:32] thread 11b5f9000 exited ("AC3 decoder")
[13:34:32] thread 119ddf000 exited ("Renderer")
[13:34:32] thread 11b576000 exited ("H.264/AVC encoder (libx264)")
[13:34:32] thread 119ab0000 exited ("MPEG-2 decoder (libmpeg2)")
[13:34:32] thread 119ab0000 joined ("MPEG-2 decoder (libmpeg2)")
[13:34:32] mpeg2 done: 138219 frames
[13:34:32] thread 119ddf000 joined ("Renderer")
[13:34:32] render: lost time: 0 (0 frames)
[13:34:32] render: gained time: 0 (0 frames) (0 not accounted for)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] thread 11b576000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:1202  Avg QP:16.81  size: 58397  PSNR Mean Y:45.95 U:48.42 V:49.35 Avg:46.68 Global:46.38
x264 [info]: frame P:55561 Avg QP:20.56  size: 10434  PSNR Mean Y:43.24 U:45.44 V:46.48 Avg:43.92 Global:43.45
x264 [info]: frame B:81455 Avg QP:22.66  size:  1754  PSNR Mean Y:42.73 U:45.27 V:46.47 Avg:43.49 Global:43.01
x264 [info]: consecutive B-frames:  5.1% 22.8% 72.1%
x264 [info]: mb I  I16..4: 20.3%  0.0% 79.7%
x264 [info]: mb P  I16..4:  1.2%  0.0%  3.3%  P16..4: 54.2% 15.5%  9.3%  0.0%  0.0%    skip:16.4%
x264 [info]: mb B  I16..4:  3.7%  0.0%  0.4%  B16..8: 18.4%  0.9%  0.6%  direct: 9.6%  skip:66.4%  L0:31.6% L1:48.5% BI:19.9%
x264 [info]: coded y,uvDC,uvAC intra: 52.5% 84.0% 59.3% inter: 13.6% 23.6% 4.4%
x264 [info]: i16 v,h,dc,p: 36% 16% 26% 22%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 27% 13% 18%  6%  7%  9%  6%  8%  6%
x264 [info]: Weighted P-Frames: Y:2.1%
x264 [info]: ref P L0: 63.4% 16.4% 20.1%
x264 [info]: SSIM Mean Y:0.9792745
x264 [info]: PSNR Mean Y:42.963 U:45.364 V:46.499 Avg:43.688 Global:43.206 kb/s:1100.09
[13:34:32] thread 11b5f9000 joined ("AC3 decoder")
[13:34:32] thread 11c2f8000 joined ("AAC encoder (Apple)")
[13:34:32] thread 119a2d000 joined ("reader")
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 1 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] fifo_close: trashing 0 buffer(s)
[13:34:32] Freed 6 buffers of size 1024
[13:34:32] Freed 32 buffers of size 2048
[13:34:32] Freed 32 buffers of size 16384
[13:34:32] Freed 32 buffers of size 524288
[13:34:32] Allocated 17897472 bytes of buffers on this pass and Freed 17373184 bytes, 524288 bytes leaked
[13:34:32] thread 119905000 exited ("work")
[13:34:32] thread 119905000 joined ("work")
[13:34:32] libhb: work result = 0
FPS: 95.151245
Filesize: 908.6MB
User avatar
Rodeo
HandBrake Team
Posts: 12626
Joined: Tue Mar 03, 2009 8:55 pm

Re: [Invalid] [SVN2937] Performance regression

Post by Rodeo »

You're not using the same x264 builds. One encode is using weighted p-frame prediction (svn2937) and one isn't (svn2902). Weighted p-frame prediction reduces performance.
siromega
Posts: 36
Joined: Mon Jul 02, 2007 5:06 am

Re: [Invalid] [SVN2937] Performance regression

Post by siromega »

Is there any way to turn it off in HB? I was reading more and in some cases it causes problem with various decoders.. (though Dark Shikari removed the list from his blog)
User avatar
JohnAStebbins
HandBrake Team
Posts: 5583
Joined: Sat Feb 09, 2008 7:21 pm

Re: [Invalid] [SVN2937] Performance regression

Post by JohnAStebbins »

weightp=0 in the advanced options manual settings box.

Latest scuttlebutt is that at least some of the device failures may be due to a bug they are tracking down right now. The failures I heard about were coreavc and appletv. I don't know if fixing the bug will fix these. The vast majority of devices tested have had no problems.
siromega
Posts: 36
Joined: Mon Jul 02, 2007 5:06 am

Re: [Invalid] [SVN2937] Performance regression

Post by siromega »

OK, thanks for the information guys!

One last thing, I tried the iPhone preset and it turns out the performance is faster in 2937 than 2902 (32.3% faster than 0.9.3 vs 28.6%). So I guess its not a complete regression...
User avatar
JohnAStebbins
HandBrake Team
Posts: 5583
Joined: Sat Feb 09, 2008 7:21 pm

Re: [Invalid] [SVN2937] Performance regression

Post by JohnAStebbins »

The iphone preset doesn't use b-frames. And weightp is automatically disabled when there are no b-frames.
Post Reply