Really poor performance under Ubuntu 12.10

[AbeFM]

Just curious if this is something on Handbrake's end, or mine. I reinstalled recently due to a number of issues, and while I was at it, I went to 12.10 from 12.04 previously.

I did a round of encodes, and it seemed a little slow, but nothing noteworthy - or maybe I just didn't look closely enough.

That said, on the same machine, where I used to be able to encode in "real time" (i.e. about 1 hour encode time for one hour of video, both passes), now I'm lucky to hit 4:1 (i.e. a half hour show takes over 2 hours to compress. In short, it's slowed down a factor of 4 or 5.

Is there something in the build that's causing this? I haven't seen any updates in a while. CPU usage is low when not compressing, and 100% across all cores when I am, so it seems to be working, and I'm using the same profile I used before.

Any advice?
-Abe.

[Deleted User 11865]

A complete encode log won't necessarily indicate the issue, but it definitely can't hurt.

[AbeFM]

Code: Select all

Handbrake Version: 0.9.8 (2012071800)
[16:36:16] hb_scan: path=/media/HBO-RAID/Ripped DVD/FullDisc/MWC S1D2, title_index=3
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening /media/HBO-RAID/Ripped DVD/FullDisc/MWC S1D2/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(/media/HBO-RAID/Ripped DVD/FullDisc/MWC S1D2) failed (0x94632130)
[16:36:16] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
[16:36:16] scan: DVD has 11 title(s)
[16:36:16] scan: scanning title 3
[16:36:16] scan: opening IFO for VTS 3
[16:36:16] scan: duration is 00:23:16 (1396286 ms)
[16:36:16] pgc_id: 1, pgn: 1: pgc: 0x9845b480
[16:36:16] scan: vts=3, ttn=1, cells=0->4, blocks=0->339332, 339333 blocks
[16:36:16] scan: checking audio 1
[16:36:16] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[16:36:16] scan: title 3 has 5 chapters
[16:36:16] scan: chap 1 c=0->0, b=0->20912 (20913), 74044 ms
[16:36:16] scan: chap 2 c=1->1, b=20913->139340 (118428), 484290 ms
[16:36:16] scan: chap 3 c=2->2, b=139341->328130 (188790), 811642 ms
[16:36:16] scan: chap 4 c=3->3, b=328131->339132 (11002), 25297 ms
[16:36:16] scan: chap 5 c=4->4, b=339133->339332 (200), 1010 ms
[16:36:16] scan: aspect = 0
[16:36:16] scan: decoding previews for title 3
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:36:16] scan: title angle(s) 1
[16:36:16] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[16:36:16] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 1.33:1, PAR 8:9
[16:36:16] Title is likely interlaced or telecined (5 out of 10 previews). You should do something about that.
[16:36:16] scan: title (0) job->width:624, job->height:480
[16:36:16] libhb: scan thread found 1 valid title(s)
[16:36:18] gtkgui: Custom Preset: Abes->AbeHighMovie
[16:36:18] 2 job(s) to process
[16:36:18] starting job
[16:36:18] sync: expecting 41846 video frames
[16:36:18] job configuration:
[16:36:18]  * source
[16:36:18]    + /media/HBO-RAID/Ripped DVD/FullDisc/MWC S1D2
[16:36:18]    + title 3, chapter(s) 1 to 5
[16:36:18]  * destination
[16:36:18]    + /media/HBO-RAID/Compressed DVD/MWC S1D2 - 2.m4v
[16:36:18]    + container: MPEG-4 (.mp4 and .m4v)
[16:36:18]      + chapter markers
[16:36:18]  * video track
[16:36:18]    + decoder: mpeg2
[16:36:18]      + bitrate 9800 kbps
[16:36:18]    + frame rate: same as source (around 29.970 fps)
[16:36:18]    + strict anamorphic
[16:36:18]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 2
[16:36:18]      + pixel aspect ratio: 8 / 9
[16:36:18]      + display dimensions: 631 * 480
[16:36:18]    + filters
[16:36:18]      + Detelecine (pullup) (default settings)
[16:36:18]      + Decomb (default settings)
[16:36:18]      + Deblock (pp7) (5)
[16:36:18]      + Denoise (hqdn3d) (2:1:2:3)
[16:36:18]    + encoder: H.264 (x264)
[16:36:18]      + options: ref=2:weightp=1:rc-lookahead=10:me=umh:subme=8:direct=auto:b-adapt=2:psy-rd=1.3|0:deblock=2,0:ref=1:subme=2:me=dia:analyse=none:trellis=0:no-fast-pskip=0:8x8dct=0
[16:36:18]      + bitrate: 1250 kbps, pass: 1
[16:36:18]  * audio track 1
[16:36:18]    + decoder: English (AC3) (2.0 ch) (track 1, id 0x80bd)
[16:36:18]      + bitrate: 192 kbps, samplerate: 48000 Hz
[16:36:18]    + mixdown: Stereo
[16:36:18]    + encoder: AAC (faac)
[16:36:18]      + bitrate: 256 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:36:18] yadif thread started for segment 0
[16:36:18] yadif thread started for segment 1
[16:36:18] yadif thread started for segment 2
[16:36:18] yadif thread started for segment 3
[16:36:18] decomb thread started for segment 0
[16:36:18] decomb thread started for segment 1
[16:36:18] decomb thread started for segment 2
[16:36:18] decomb thread started for segment 3
[16:36:18] encx264: min-keyint: 30, keyint: 300
[16:36:18] encx264: encoding with stored aspect 8/9
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=8/9
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
x264 [info]: profile Main, level 3.0
[16:36:18] reader: first SCR 146 id 0xe0 DTS 732
[16:36:18] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[16:36:18] sync: first pts is 3003
[16:38:17] mpeg2: "Chapter  2" (2) at frame 2230 time 6699693
[16:52:23] mpeg2: "Chapter  3" (3) at frame 16752 time 50309259
[17:15:33] mpeg2: "Chapter  4" (4) at frame 41090 time 123396273
[17:16:24] mpeg2: "Chapter  5" (5) at frame 41868 time 125732607
[17:16:26] reader: done. 1 scr changes
[17:16:28] work: average encoding speed for job is 17.396345 fps
[17:16:28] sync: got 41906 frames, 41846 expected
[17:16:28] mpeg2 done: 41906 frames
[17:16:28] render: lost time: 702702 (234 frames)
[17:16:28] render: gained time: 702702 (926 frames) (0 not accounted for)
[17:16:28] render: average dropped frame duration: 3003
x264 [info]: frame I:225   Avg QP:16.42  size: 36807  PSNR Mean Y:48.74 U:51.68 V:51.55 Avg:49.41 Global:48.01
x264 [info]: frame P:12792 Avg QP:20.02  size: 11052  PSNR Mean Y:44.68 U:48.43 V:47.98 Avg:45.51 Global:44.48
x264 [info]: frame B:28654 Avg QP:23.19  size:  2552  PSNR Mean Y:43.06 U:47.43 V:47.17 Avg:44.03 Global:43.61
x264 [info]: consecutive B-frames:  3.1%  6.8% 26.7% 63.4%
x264 [info]: mb I  I16..4: 14.9%  0.0% 85.1%
x264 [info]: mb P  I16..4:  9.6%  0.0%  0.0%  P16..4: 75.8%  0.0%  0.0%  0.0%  0.0%    skip:14.6%
x264 [info]: mb B  I16..4:  1.1%  0.0%  0.0%  B16..8: 22.6%  0.0%  0.0%  direct:13.5%  skip:62.8%  L0:25.0% L1:44.7% BI:30.3%
x264 [info]: final ratefactor: 18.56
x264 [info]: direct mvs  spatial:99.9% temporal:0.1%
x264 [info]: coded y,uvDC,uvAC intra: 53.1% 65.5% 24.6% inter: 19.5% 17.9% 1.9%
x264 [info]: i16 v,h,dc,p: 47% 28% 16%  9%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 26% 26%  7%  6%  7%  7%  8%  6%  7%
x264 [info]: i8c dc,h,v,p: 46% 20% 26%  8%
x264 [info]: Weighted P-Frames: Y:1.3% UV:0.8%
x264 [info]: SSIM Mean Y:0.9865557 (18.715db)
x264 [info]: PSNR Mean Y:43.588 U:47.762 V:47.445 Avg:44.514 Global:43.873 kb/s:1274.66
[17:16:28] decomb: deinterlaced 30190 | blended 3005 | unfiltered 8476 | total 41671
[17:16:28] starting job
[17:16:28] sync: expecting 41906 video frames
[17:16:28] job configuration:
[17:16:28]  * source
[17:16:28]    + /media/HBO-RAID/Ripped DVD/FullDisc/MWC S1D2
[17:16:28]    + title 3, chapter(s) 1 to 5
[17:16:28]  * destination
[17:16:28]    + /media/HBO-RAID/Compressed DVD/MWC S1D2 - 2.m4v
[17:16:28]    + container: MPEG-4 (.mp4 and .m4v)
[17:16:28]      + chapter markers
[17:16:28]  * video track
[17:16:28]    + decoder: mpeg2
[17:16:28]      + bitrate 9800 kbps
[17:16:28]    + frame rate: same as source (around 29.970 fps)
[17:16:28]    + strict anamorphic
[17:16:28]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 2
[17:16:28]      + pixel aspect ratio: 8 / 9
[17:16:28]      + display dimensions: 631 * 480
[17:16:28]    + filters
[17:16:28]      + Detelecine (pullup) (default settings)
[17:16:28]      + Decomb (default settings)
[17:16:28]      + Deblock (pp7) (5)
[17:16:28]      + Denoise (hqdn3d) (2:1:2:3)
[17:16:28]    + encoder: H.264 (x264)
[17:16:28]      + options: ref=2:weightp=1:rc-lookahead=10:me=umh:subme=8:direct=auto:b-adapt=2:psy-rd=1.3|0:deblock=2,0
[17:16:28]      + bitrate: 1250 kbps, pass: 2
[17:16:28]  * subtitle track 0, Closed Captions (id 0x0) Text [CC] -> Pass-Through
[17:16:28]  * audio track 1
[17:16:28]    + decoder: English (AC3) (2.0 ch) (track 1, id 0x80bd)
[17:16:28]      + bitrate: 192 kbps, samplerate: 48000 Hz
[17:16:28]    + mixdown: Stereo
[17:16:28]    + encoder: AAC (faac)
[17:16:28]      + bitrate: 256 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[17:16:28] yadif thread started for segment 0
[17:16:28] yadif thread started for segment 1
[17:16:28] yadif thread started for segment 2
[17:16:28] yadif thread started for segment 3
[17:16:28] decomb thread started for segment 0
[17:16:28] decomb thread started for segment 1
[17:16:28] decomb thread started for segment 2
[17:16:28] decomb thread started for segment 3
[17:16:28] reader: first SCR 146 id 0xe0 DTS 732
[17:16:28] encx264: min-keyint: 30, keyint: 300
[17:16:28] encx264: encoding with stored aspect 8/9
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=8/9
[17:16:28] mpeg2: "Chapter  1" (1) at frame 0 time 3003
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
x264 [info]: profile High, level 3.0
[17:16:29] sync: first pts is 3003
[17:20:55] mpeg2: "Chapter  2" (2) at frame 2230 time 6699693
[17:55:38] mpeg2: "Chapter  3" (3) at frame 16752 time 50309259
[18:52:24] mpeg2: "Chapter  4" (4) at frame 41090 time 123396273
[18:54:13] mpeg2: "Chapter  5" (5) at frame 41868 time 125732607
[18:54:17] reader: done. 1 scr changes
[18:54:20] work: average encoding speed for job is 7.138183 fps
[18:54:20] sync: got 41906 frames, 41906 expected
[18:54:20] mpeg2 done: 41906 frames
[18:54:20] render: lost time: 702702 (234 frames)
[18:54:20] render: gained time: 702702 (926 frames) (0 not accounted for)
[18:54:20] render: average dropped frame duration: 3003
x264 [info]: frame I:225   Avg QP:17.59  size: 33128  PSNR Mean Y:48.44 U:52.16 V:52.02 Avg:49.27 Global:47.97
x264 [info]: frame P:12792 Avg QP:20.71  size: 10387  PSNR Mean Y:45.27 U:49.39 V:49.04 Avg:46.18 Global:45.33
x264 [info]: frame B:28654 Avg QP:24.32  size:  2728  PSNR Mean Y:43.54 U:48.12 V:48.01 Avg:44.57 Global:44.24
x264 [info]: consecutive B-frames:  3.1%  6.8% 26.7% 63.4%
x264 [info]: mb I  I16..4:  9.6% 69.2% 21.1%
x264 [info]: mb P  I16..4:  1.6%  6.4%  1.2%  P16..4: 39.8% 24.0% 11.4%  0.0%  0.0%    skip:15.7%
x264 [info]: mb B  I16..4:  0.1%  0.3%  0.1%  B16..8: 36.4%  5.8%  1.3%  direct: 3.5%  skip:52.4%  L0:35.2% L1:51.1% BI:13.7%
x264 [info]: 8x8 transform intra:69.1% inter:74.0%
x264 [info]: direct mvs  spatial:90.3% temporal:9.7%
x264 [info]: coded y,uvDC,uvAC intra: 73.3% 76.1% 33.5% inter: 21.3% 22.8% 2.5%
x264 [info]: i16 v,h,dc,p: 52% 16%  4% 28%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 15% 13%  6%  7% 11% 13% 12% 11% 13%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16% 13%  3%  7% 12% 13% 14%  9% 12%
x264 [info]: i8c dc,h,v,p: 34% 24% 23% 19%
x264 [info]: Weighted P-Frames: Y:1.3% UV:0.8%
x264 [info]: ref P L0: 66.6% 33.4%
x264 [info]: ref B L0: 87.0% 13.0%
x264 [info]: ref B L1: 95.4%  4.6%
x264 [info]: SSIM Mean Y:0.9878038 (19.138db)
x264 [info]: PSNR Mean Y:44.100 U:48.531 V:48.350 Avg:45.086 Global:44.560 kb/s:1250.08
[18:54:20] mux: track 0, 41671 frames, 218477702 bytes, 1249.96 kbps, fifo 4096
[18:54:20] mux: track 1, 65546 frames, 44691747 bytes, 255.69 kbps, fifo 4096
[18:54:20] mux: track 2, 493 frames, 15988 bytes, 0.09 kbps, fifo 64
[18:54:20] decomb: deinterlaced 30190 | blended 3005 | unfiltered 8476 | total 41671
[18:54:20] libhb: work result = 0

I had the logging set to regular, then switched it up, not sure if this is verbose or not.

[Smithcraft]

A comparison log might also be helpful.

SC

[AbeFM]

Meaning before it was slow? Uhg. I am not *sure* when it got slow, I only noticed it for sure recently. I have them back to when I changed OS's, but the drive was wiped in between so I don't have the old stuff. I'll jump on other machine and post oldest I can find.

[AbeFM]

No go, the oldest log I have is from this same batch.... Here it is anyway.

Code: Select all

Handbrake Version: 0.9.8 (2012071800)
[20:37:18] hb_scan: path=/media/HBO-RAID/Ripped DVD/FullDisc/BREAKING_BAD_SEASON_4_DISC_1, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening /media/HBO-RAID/Ripped DVD/FullDisc/BREAKING_BAD_SEASON_4_DISC_1/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(/media/HBO-RAID/Ripped DVD/FullDisc/BREAKING_BAD_SEASON_4_DISC_1) failed (0xb24fe400)
[20:37:18] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
[20:37:18] scan: DVD has 19 title(s)
[20:37:18] scan: scanning title 1
[20:37:18] scan: opening IFO for VTS 1
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
[20:37:18] scan: duration is 00:47:08 (2828320 ms)
[20:37:18] pgc_id: 1, pgn: 1: pgc: 0xb24fd628
[20:37:18] scan: vts=1, ttn=1, cells=0->6, blocks=0->848037, 848038 blocks
[20:37:18] scan: checking audio 1
[20:37:18] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[20:37:18] scan: checking audio 2
[20:37:18] scan: id=0x81bd, lang=English (AC3), 3cc=eng ext=0
[20:37:18] scan: checking subtitle 1
[20:37:18] scan: id=0x20bd, lang=Francais, 3cc=fra
[20:37:18] scan: checking subtitle 2
[20:37:18] scan: id=0x21bd, lang=Espanol, 3cc=spa
[20:37:18] scan: checking subtitle 3
[20:37:18] scan: id=0x22bd, lang=Espanol, 3cc=spa
[20:37:18] scan: title 1 has 7 chapters
[20:37:18] scan: chap 1 c=0->0, b=0->90376 (90377), 287283 ms
[20:37:18] scan: chap 2 c=1->1, b=90377->304490 (214114), 699746 ms
[20:37:18] scan: chap 3 c=2->2, b=304491->471113 (166623), 583487 ms
[20:37:18] scan: chap 4 c=3->3, b=471114->678434 (207321), 678642 ms
[20:37:18] scan: chap 5 c=4->4, b=678435->840702 (162268), 535678 ms
[20:37:18] scan: chap 6 c=5->5, b=840703->847951 (7249), 42305 ms
[20:37:18] scan: chap 7 c=6->6, b=847952->848037 (86), 1176 ms
[20:37:18] scan: aspect = 0
[20:37:18] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
[20:37:18] scan: title angle(s) 1
[20:37:18] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[20:37:18] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[20:37:18] scan: content PAR gives wrong aspect 1.78; using container aspect 1.78
[20:37:18] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 1.78:1, PAR 853:720
[20:37:18] scan: title (0) job->width:720, job->height:400
[20:37:18] libhb: scan thread found 1 valid title(s)
[20:37:20] gtkgui: Custom Preset: Abes->AbeHighMovie
[20:37:20] 2 job(s) to process
[20:37:20] starting job
[20:37:20] sync: expecting 67811 video frames
[20:37:20] job configuration:
[20:37:20]  * source
[20:37:20]    + /media/HBO-RAID/Ripped DVD/FullDisc/BREAKING_BAD_SEASON_4_DISC_1
[20:37:20]    + title 1, chapter(s) 1 to 7
[20:37:20]  * destination
[20:37:20]    + /home/abe/Videos/Breaking Bad Season 4 Disc 1 - 1.m4v
[20:37:20]    + container: MPEG-4 (.mp4 and .m4v)
[20:37:20]      + chapter markers
[20:37:20]  * video track
[20:37:20]    + decoder: mpeg2
[20:37:20]      + bitrate 9800 kbps
[20:37:20]    + frame rate: same as source (around 23.976 fps)
[20:37:20]    + strict anamorphic
[20:37:20]      + storage dimensions: 720 * 480 -> 720 * 480, crop 0/0/0/0, mod 2
[20:37:20]      + pixel aspect ratio: 853 / 720
[20:37:20]      + display dimensions: 853 * 480
[20:37:20]    + filters
[20:37:20]      + Detelecine (pullup) (default settings)
[20:37:20]      + Decomb (default settings)
[20:37:20]      + Deblock (pp7) (5)
[20:37:20]      + Denoise (hqdn3d) (2:1:2:3)
[20:37:20]    + encoder: H.264 (x264)
[20:37:20]      + options: ref=2:weightp=1:rc-lookahead=10:me=umh:subme=8:direct=auto:b-adapt=2:psy-rd=1.3|0:deblock=2,0:ref=1:subme=2:me=dia:analyse=none:trellis=0:no-fast-pskip=0:8x8dct=0
[20:37:20]      + bitrate: 1250 kbps, pass: 1
[20:37:20]  * audio track 1
[20:37:20]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[20:37:20]      + bitrate: 384 kbps, samplerate: 48000 Hz
[20:37:20]    + mixdown: 6-channel discrete
[20:37:20]    + encoder: AAC (faac)
[20:37:20]      + bitrate: 256 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
[20:37:20] yadif thread started for segment 0
[20:37:20] yadif thread started for segment 1
[20:37:20] yadif thread started for segment 2
[20:37:20] yadif thread started for segment 3
[20:37:20] decomb thread started for segment 0
[20:37:20] decomb thread started for segment 1
[20:37:20] decomb thread started for segment 2
[20:37:20] decomb thread started for segment 3
[20:37:20] reader: first SCR 146 id 0xe0 DTS 18018
[20:37:20] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[20:37:20] encx264: min-keyint: 24, keyint: 240
[20:37:20] encx264: encoding with stored aspect 853/720
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=853/720
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
x264 [info]: profile Main, level 3.0
[20:37:20] sync: first pts is 3003
[20:42:59] mpeg2: "Chapter  2" (2) at frame 6918 time 25924899
[20:57:05] mpeg2: "Chapter  3" (3) at frame 23716 time 88921833
[21:08:31] mpeg2: "Chapter  4" (4) at frame 37732 time 141510369
[21:21:52] mpeg2: "Chapter  5" (5) at frame 54023 time 202636434
[21:32:00] 2763.727633s: Film -> Video
[21:32:00] 2763.761000s: Video -> Film
[21:32:31] mpeg2: "Chapter  6" (6) at frame 66909 time 250891641
[21:33:23] reader: done. 1 scr changes
[21:33:24] mpeg2: "Chapter  7" (7) at frame 68120 time 254729475
[21:33:27] work: average encoding speed for job is 20.248259 fps
[21:33:27] sync: got 68165 frames, 67811 expected
[21:33:27] mpeg2 done: 68165 frames
[21:33:27] render: lost time: 54054 (18 frames)
[21:33:27] render: gained time: 54054 (67 frames) (0 not accounted for)
[21:33:27] render: average dropped frame duration: 3003
x264 [info]: frame I:536   Avg QP: 9.70  size: 44926  PSNR Mean Y:53.34 U:54.72 V:55.00 Avg:53.56 Global:52.76
x264 [info]: frame P:22113 Avg QP:13.30  size: 12454  PSNR Mean Y:49.98 U:52.08 V:52.35 Avg:50.37 Global:49.49
x264 [info]: frame B:45497 Avg QP:17.64  size:  3085  PSNR Mean Y:48.21 U:51.10 V:51.44 Avg:48.86 Global:48.40
x264 [info]: consecutive B-frames:  5.0%  5.7% 36.7% 52.6%
x264 [info]: mb I  I16..4: 28.4%  0.0% 71.6%
x264 [info]: mb P  I16..4: 16.1%  0.0%  0.0%  P16..4: 66.7%  0.0%  0.0%  0.0%  0.0%    skip:17.3%
x264 [info]: mb B  I16..4:  1.8%  0.0%  0.0%  B16..8: 32.6%  0.0%  0.0%  direct:12.8%  skip:52.8%  L0:31.9% L1:42.0% BI:26.2%
x264 [info]: final ratefactor: 14.46
x264 [info]: direct mvs  spatial:99.3% temporal:0.7%
x264 [info]: coded y,uvDC,uvAC intra: 46.5% 70.8% 53.1% inter: 18.6% 18.4% 3.5%
x264 [info]: i16 v,h,dc,p: 63% 19% 13%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 35% 21%  8%  6%  7%  7%  5%  6%  5%
x264 [info]: i8c dc,h,v,p: 46% 15% 32%  7%
x264 [info]: Weighted P-Frames: Y:1.0% UV:0.4%
x264 [info]: SSIM Mean Y:0.9943970 (22.516db)
x264 [info]: PSNR Mean Y:48.821 U:51.449 V:51.757 Avg:49.387 Global:48.747 kb/s:1242.56
[21:33:27] decomb: deinterlaced 0 | blended 1 | unfiltered 68145 | total 68146
[21:33:27] starting job
[21:33:27] sync: expecting 68165 video frames
[21:33:27] job configuration:
[21:33:27]  * source
[21:33:27]    + /media/HBO-RAID/Ripped DVD/FullDisc/BREAKING_BAD_SEASON_4_DISC_1
[21:33:27]    + title 1, chapter(s) 1 to 7
[21:33:27]  * destination
[21:33:27]    + /home/abe/Videos/Breaking Bad Season 4 Disc 1 - 1.m4v
[21:33:27]    + container: MPEG-4 (.mp4 and .m4v)
[21:33:27]      + chapter markers
[21:33:27]  * video track
[21:33:27]    + decoder: mpeg2
[21:33:27]      + bitrate 9800 kbps
[21:33:27]    + frame rate: same as source (around 23.976 fps)
[21:33:27]    + strict anamorphic
[21:33:27]      + storage dimensions: 720 * 480 -> 720 * 480, crop 0/0/0/0, mod 2
[21:33:27]      + pixel aspect ratio: 853 / 720
[21:33:27]      + display dimensions: 853 * 480
[21:33:27]    + filters
[21:33:27]      + Detelecine (pullup) (default settings)
[21:33:27]      + Decomb (default settings)
[21:33:27]      + Deblock (pp7) (5)
[21:33:27]      + Denoise (hqdn3d) (2:1:2:3)
[21:33:27]    + encoder: H.264 (x264)
[21:33:27]      + options: ref=2:weightp=1:rc-lookahead=10:me=umh:subme=8:direct=auto:b-adapt=2:psy-rd=1.3|0:deblock=2,0
[21:33:27]      + bitrate: 1250 kbps, pass: 2
[21:33:27]  * subtitle track 0, Closed Captions (id 0x0) Text [CC] -> Pass-Through
[21:33:27]  * audio track 1
[21:33:27]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[21:33:27]      + bitrate: 384 kbps, samplerate: 48000 Hz
[21:33:27]    + mixdown: 6-channel discrete
[21:33:27]    + encoder: AAC (faac)
[21:33:27]      + bitrate: 256 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/abe/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:2088
    for vts_attributes->vtstt_audio_attr[i] = 0x10a36e6500000000
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
*** Zero check failed in ifo_read.c:653
    for vtsi_mat->vts_audio_attr[i] = 0x10a36e6500000000
[21:33:27] yadif thread started for segment 0
[21:33:27] yadif thread started for segment 1
[21:33:27] yadif thread started for segment 2
[21:33:27] yadif thread started for segment 3
[21:33:27] decomb thread started for segment 0
[21:33:27] decomb thread started for segment 1
[21:33:27] decomb thread started for segment 3
[21:33:27] decomb thread started for segment 2
[21:33:27] encx264: min-keyint: 24, keyint: 240
[21:33:27] encx264: encoding with stored aspect 853/720
x264 [warning]: --psnr used with psy on: results will be invalid!
x264 [warning]: --tune psnr should be used if attempting to benchmark psnr!
x264 [info]: using SAR=853/720
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2 AVX
[21:33:27] reader: first SCR 146 id 0xe0 DTS 18018
[21:33:27] mpeg2: "Chapter  1" (1) at frame 0 time 3003
x264 [info]: profile High, level 3.0
[21:33:30] sync: first pts is 3003
[21:50:12] mpeg2: "Chapter  2" (2) at frame 6918 time 25924899
[22:31:11] mpeg2: "Chapter  3" (3) at frame 23716 time 88921833
[23:03:51] mpeg2: "Chapter  4" (4) at frame 37732 time 141510369
[23:43:14] mpeg2: "Chapter  5" (5) at frame 54023 time 202636434
[00:12:40] 2763.727633s: Film -> Video
[00:12:40] 2763.761000s: Video -> Film
[00:14:10] mpeg2: "Chapter  6" (6) at frame 66909 time 250891641
[00:15:20] reader: done. 1 scr changes
[00:15:21] mpeg2: "Chapter  7" (7) at frame 68120 time 254729475
[00:15:29] work: average encoding speed for job is 7.013598 fps
[00:15:29] sync: got 68165 frames, 68165 expected
[00:15:29] mpeg2 done: 68165 frames
[00:15:29] render: lost time: 54054 (18 frames)
[00:15:29] render: gained time: 54054 (67 frames) (0 not accounted for)
[00:15:29] render: average dropped frame duration: 3003
x264 [info]: frame I:536   Avg QP:10.44  size: 43757  PSNR Mean Y:53.07 U:55.42 V:55.69 Avg:53.57 Global:52.86
x264 [info]: frame P:22113 Avg QP:13.39  size: 12282  PSNR Mean Y:50.75 U:53.39 V:53.66 Avg:51.29 Global:50.49
x264 [info]: frame B:45497 Avg QP:17.90  size:  3240  PSNR Mean Y:48.95 U:52.16 V:52.53 Avg:49.68 Global:49.30
x264 [info]: consecutive B-frames:  5.0%  5.7% 36.7% 52.6%
x264 [info]: mb I  I16..4: 15.9% 53.9% 30.3%
x264 [info]: mb P  I16..4:  3.2%  6.8%  2.3%  P16..4: 29.9% 24.3% 15.4%  0.0%  0.0%    skip:18.2%
x264 [info]: mb B  I16..4:  0.2%  0.5%  0.2%  B16..8: 37.4% 10.0%  2.1%  direct: 3.6%  skip:46.0%  L0:36.9% L1:45.7% BI:17.4%
x264 [info]: 8x8 transform intra:55.1% inter:66.5%
x264 [info]: direct mvs  spatial:93.6% temporal:6.4%
x264 [info]: coded y,uvDC,uvAC intra: 60.5% 80.2% 64.4% inter: 19.5% 20.9% 5.4%
x264 [info]: i16 v,h,dc,p: 69% 10%  5% 16%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 24% 11% 18%  6%  7% 10%  7%  9%  8%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 23% 10% 12%  7% 11% 13%  9%  9%  7%
x264 [info]: i8c dc,h,v,p: 41% 18% 27% 13%
x264 [info]: Weighted P-Frames: Y:1.0% UV:0.4%
x264 [info]: ref P L0: 67.8% 32.2%
x264 [info]: ref B L0: 84.2% 15.8%
x264 [info]: ref B L1: 93.1%  6.9%
x264 [info]: SSIM Mean Y:0.9951822 (23.171db)
x264 [info]: PSNR Mean Y:49.566 U:52.581 V:52.920 Avg:50.231 Global:49.670 kb/s:1249.97
[00:15:29] mux: track 0, 68146 frames, 442430093 bytes, 1249.90 kbps, fifo 2048
[00:15:29] mux: track 1, 132740 frames, 90555171 bytes, 255.82 kbps, fifo 4096
[00:15:29] mux: track 2, 521 frames, 15105 bytes, 0.04 kbps, fifo 32
[00:15:29] decomb: deinterlaced 0 | blended 1 | unfiltered 68145 | total 68146
[00:15:29] libhb: work result = 0

[Deleted User 11865]

Speed seems very similar in both cases (17-20 fps first pass, 7 fps second pass).

As a side note, 256 Kbps for 6-channel discrete w/faac is not a recipe for good quality audio.

[AbeFM]

Yeah - I have to post another thread later, what to tweak to get better speed at minimal quality hits. In general, I've been very happy with the speed/quality trade offs I've made (though I'm no audiophile, I'm mostly talking in the video dept)....

But as I said, these are all from one long encode - something like this, 150 files, would take a couple days, but I'm less than half way through it in a week. So the log is the same old one. I definitely would get through stuff in real time, i.e. fist pass at 100 fps, second at ~28, so one hour of video would take about 55-60 minutes to compress. I'm nowhere near that now.

Have there been nightly builds posting? I'm wondering if my system isn't updating, because I haven't gotten a new version of handbrake in a long time. Oh! I think I switched to the stable release. Maybe there's my issue? Odd it would be so slow. Could be that or 12.10, maybe it's slow? If so, though, I would expect the CPU's not to be loaded.

[Deleted User 11865]

Are you sure you were using deblock & denoise before? Those filters are not threaded. Also, decomb got slower between 0.9.5 and 0.9.6 (but more accurate).

[AbeFM]

While it's certainly possible that some changes snuck in, really, I just back up my old settings, then reload them.

There's a chance that as the program moves forward, the old save files are formatted differently, turning something on? Somewhat unlikely, but not unreasonable.

I think I do remember deblock/denoise making a big difference. The thing that's weird is it's taking SO much longer (or, you're saying it's only 1 core working on it at a time due to the threading??)... I guess perhaps I loaded that and tweaked it before, but it seems odd. I'm leaning more towards an OS thing.

Is decomb smart (i.e. will it not run if not needed)? I use different settings for anime as for live action, but those were both live action so I wouldn't think my denoise/comb settings would be different. Still, I could turn those off and see what I get.

[Deleted User 11865]

Combing detection always runs. Deinterlacing is then applied selectively when necessary.

[mus]

this may be of interest to you:
https://bugzilla.redhat.com/show_bug.cgi?id=847551

this seems to be a regression introduced in kernel 3.5. It is still not clear whether it is actually the kernel's fault or if there is a bug in x264 or ffmpeg which was "triggered" by a change introduced in kernel 3.5.

Plase comment on the bug report, maybe then this will get more attention and we can narrow down the problem.

[JohnAStebbins]

Could you try a nightly build? There was a bug in libav that was fixed after 0.9.8. We didn't think it affected the release because it seemed to be caused by a code change in an intermediate version. But maybe the bug was already there and just required subtly different timing or something to provoke it.

Reference to the bug I'm speaking of
http://lists.libav.org/pipermail/libav- ... 31698.html

[AbeFM]

I would be quite happy to try a nightly build - I was for a long time. I'll go back to that set up when next I get to play computer geek. I've been playing mechanic geek for the past week (hence the slow response) - new motor, trans, diff, shocks, short shifter, turbo, exhaust etc in car. Been busy!

Will definitely check out those bugs.

[AbeFM]

REALLY sorry about taking so long to get to this. A couple rounds of family visits and as I mentioned, all new car has left me pretty much at wit's end for free time.

Got nightly build installed, running first test case. 3 minutes in, first pass looks 40% faster. Second pass will tell the tale. Didn't even reboot between tests.

As a side note, the repositories weren't working, had to get it for precise. That's the only reason I haven't been running it all along.

Will post another update in some hours (or tomorrow) when I have more info to share.

[AbeFM]

Definately not back to normal. I didn't want to judge on first pass (which is typically 80-100 fps verses the 35 I'm getting now) but the second pass is right where it was, 7-8 fps (verses 22-25).

Not sure what else I can do to help, but it's about 4.5 times as slow as it originally was, or right about where it was when I first posted. Happy to try anything you think will be useful in tracking this down.

[JohnAStebbins]

My snapshot builds were not firing off automatically (little glitch after 12.10. upgrade). There should be a real quantal snapshot available by tomorrow. A 0.9.8 release is already available for quantal.

I haven't tested "precise" builds on quantal, but running builds I make myself (which should be the same as snapshot builds), I am getting about 900fps encoding DVD material using the equivalent of x264's ultrafast preset and I get about 10fps using the equivalent of x264's placebo preset. That seems pretty normal to me. This is on a 6 core sandybridge i7.

[AbeFM]

The interesting thing is I made no changes - I mean, same hardware, just took off a flakey Precise and put on a clean Quantal. (I so prefer version numbers. Anyway)

The change is quite dramatic. I was running the released 0.9.8 till last night. The precise build I've got on there now is 5025svn. Very consistent average of 7.5 fps compared to mid twenties before. Compressed 3 shows* in the time I would ordinarily have done 15 or so. I loaded the old presets, so unless:
1) I totally loaded the wrong presets from some even older set
2) The new version interpreted the presets wrong on the import
then it's either the OS or HB itself.

Perhaps there's a well known test file I could run with one of the built in presets to answer this better?
-Abe.

P.S. System: i3-2500T, plenty of ram and a mid level motherboard. As I said, it ran very well before the updates. Actually pretty particular with my settings since defects really show on the bigger screen that I have - but I'll admit to not knowing what they all do.

[AbeFM]

Hmmm, I have an idea (after a 3 day trip yielded me <15 hours of video)... I am wondering, is there some way I could tell from a compressed file itself the settings that were used to create it? I could compare the settings from videos I know went fast to the current settings.

I'm starting to agree it's a settings thing, perhaps I just need to find a good place to learn about where the low hanging fruit is for quality vs compression time.

[JohnAStebbins]

mediainfo

[AbeFM]

Thanks!
That seems to be better developed for windows than linux, thankfully I have both.

Here's what I found. Probably only the 1st and 3rd of this group are worth looking at....

New - (9/25/12) Breaking Bad S4
<Encoded_Library_Settings>cabac=1 / ref=2 / deblock=1:2:0 / analyse=0x3:0x113 / me=umh / subme=8 / psy=1 / psy_rd=1.30:0.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=-2 / threads=6 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=2 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=1 / keyint=240 / keyint_min=24 / scenecut=40 / intra_refresh=0 / rc_lookahead=10 / rc=2pass / mbtree=1 / bitrate=1250 / ratetol=1.0 / qcomp=0.60 / qpmin=3 / qpmax=69 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / aq=1:1.00</Encoded_Library_Settings>

<Duration_String>47mn 12s</Duration_String>
<OverallBitRate_Mode_String>VBR</OverallBitRate_Mode_String>
<OverallBitRate_String>1507 Kbps</OverallBitRate_String>
<Encoded_Date>UTC 2012-09-26 01:50:46</Encoded_Date>
<Tagged_Date>UTC 2012-09-26 04:28:43</Tagged_Date>
<Encoded_Application>HandBrake 0.9.8 2012071800</Encoded_Application>

This is one I'm nearly positive ran slow. 0:47 hours long and took 2:38 to compress (3.36:1)

Much Newer - (10/5/12) Married With Children S3
<Encoded_Library_Settings>cabac=1 / ref=2 / deblock=1:2:0 / analyse=0x3:0x113 / me=umh / subme=8 / psy=1 / psy_rd=1.30:0.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=-2 / threads=6 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=2 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=1 / keyint=300 / keyint_min=30 / scenecut=40 / intra_refresh=0 / rc_lookahead=10 / rc=2pass / mbtree=1 / bitrate=1250 / ratetol=1.0 / qcomp=0.60 / qpmin=3 / qpmax=69 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / aq=1:1.00</Encoded_Library_Settings>

This was completely assuredly slow - it happened AFTER I posted that I was having issues.

Older (6/12) Another Earth:
<Encoded_Library_Settings>cabac=1 / ref=2 / deblock=1:2:0 / analyse=0x3:0x113 / me=umh / subme=8 / psy=1 / psy_rd=1.30:0.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=-2 / threads=6 / lookahead_threads=1 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=2 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=1 / keyint=240 / keyint_min=24 / scenecut=40 / intra_refresh=0 / rc_lookahead=10 / rc=2pass / mbtree=1 / bitrate=1250 / ratetol=1.0 / qcomp=0.60 / qpmin=3 / qpmax=69 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / aq=1:1.00</Encoded_Library_Settings>

<Duration_String>1h 32mn</Duration_String>
<OverallBitRate_Mode_String>VBR</OverallBitRate_Mode_String>
<OverallBitRate_String>1515 Kbps</OverallBitRate_String>
<Encoded_Date>UTC 2012-06-08 21:03:00</Encoded_Date>
<Tagged_Date>UTC 2012-06-08 22:28:21</Tagged_Date>
<Encoded_Application>HandBrake 4714svn 2012060701</Encoded_Application>

This is one that took a reasonable amount of time. It's an 1:32 long and it took 1:25 to compress (0.92:1), assuming I'm reading this right.

Much Older (6/11) Monk S7
<Encoded_Library_Settings>cabac=1 / ref=3 / deblock=1:0:0 / analyse=0x3:0x133 / me=umh / subme=9 / psy=1 / psy_rd=1.00:1.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=0 / chroma_qp_offset=-4 / threads=6 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / constrained_intra=0 / bframes=6 / b_pyramid=2 / b_adapt=1 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=2 / keyint=240 / keyint_min=23 / scenecut=40 / intra_refresh=0 / rc_lookahead=40 / rc=2pass / mbtree=1 / bitrate=1000 / ratetol=1.0 / qcomp=0.60 / qpmin=3 / qpmax=51 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / aq=1:1.00</Encoded_Library_Settings>

Old (Pre 9/12) - City of God:
<Encoded_Library_Settings>cabac=1 / ref=2 / deblock=1:2:0 / analyse=0x3:0x113 / me=umh / subme=8 / psy=1 / psy_rd=1.30:0.00 / mixed_ref=1 / me_range=16 / chroma_me=1 / trellis=1 / 8x8dct=1 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=-2 / threads=6 / lookahead_threads=1 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=2 / b_bias=0 / direct=3 / weightb=1 / open_gop=0 / weightp=1 / keyint=240 / keyint_min=24 / scenecut=40 / intra_refresh=0 / rc_lookahead=10 / rc=2pass / mbtree=1 / bitrate=1250 / ratetol=1.0 / qcomp=0.60 / qpmin=3 / qpmax=69 / qpstep=4 / cplxblur=20.0 / qblur=0.5 / ip_ratio=1.40 / aq=1:1.00</Encoded_Library_Settings>

So, what am I missing? There sure doesn't seem to be a lot of difference, except that more is reported in the more modern encoder. Seems I might be looking in the right place, but I'm not seeing anything with my untrained eye.

[JohnAStebbins]

I don't see anything in the settings that would cause the problem. But just to be sure, try encoding with one of the built-in presets instead of your custom settings. Check the speed of the "Normal" preset for example.

[AbeFM]

Seems like 42 avg fps on "Swamp Thing" the original movie. Very different settings than I normally use. About 2/3 of the way through.

[JohnAStebbins]

Is that with standard definition content?

With the official 0.9.8 release, "Normal" preset, standard definition content, I get:
Fedora 17 laptop 64bit dual-core with hyperthreading 180fps
Ubuntu 12.10 64bit hex-core i7 350fps
Ubuntu 12.10 32bit 4 core virtualbox 180fps

[AbeFM]

Yep, Swamp Thing is 480. Yes, I hit "update presets" and then ran a "normal" compression. 42 fps, or 91 min in 54 for a ratio of 0.59

cabac=1 / ref=1 / deblock=1:0:0 / analyse=0x1:0x111 / me=hex / subme=2 / psy=1 / psy_rd=1.00:0.00 / mixed_ref=0 / me_range=16 / chroma_me=1 / trellis=0 / 8x8dct=0 / cqm=0 / deadzone=21,11 / fast_pskip=1 / chroma_qp_offset=0 / threads=6 / lookahead_threads=1 / sliced_threads=0 / nr=0 / decimate=1 / interlaced=0 / bluray_compat=0 / constrained_intra=0 / bframes=3 / b_pyramid=2 / b_adapt=1 / b_bias=0 / direct=1 / weightb=1 / open_gop=0 / weightp=1 / keyint=240 / keyint_min=24 / scenecut=40 / intra_refresh=0 / rc_lookahead=10 / rc=crf / mbtree=1 / crf=20.0 / qcomp=0.60 / qpmin=3 / qpmax=69 / qpstep=4 / ip_ratio=1.40 / aq=1:1.00

Duration : 1h 31mn
Overall bit rate mode : Variable
Overall bit rate : 1 453 Kbps
Encoded date : UTC 2012-10-30 20:10:22
Tagged date : UTC 2012-10-30 21:06:37
Writing application : HandBrake 5042svn 2012102801

Width : 716 pixels
Height : 472 pixels

I think stuff is just running really slowly. Not sure why though. I get HB using 370% of CPU, and sysmonitor using up most of the rest. No significant ram is being used off anything, and while the media comes off a magnetic RAID5, HB and the OS and temp files are all installed on a pretty fast SSD.

No idea why it's so slow. Glad to look up anything I can to help.