"stop-at" written incorrectly to batch script & export queue

[zeddy]

Handbrake 0.9.8 (2012071700)

It appears that the stop-at parameter is not being written correctly to the batch script.

1. start handbrake
2. choose an input file (I used a 1 hour .avi file)
3. choose 'frames' from the chapters/seconds/frames list, then enter "2000 through 3000" (it shows duration: 00:00:33.367000 for my 29 fps file, which seems about right)
4. add to queue
5. go to the queue window, and choose 'generate batch script' and save to test.bat
6. now examine test.bat
7. here's what I see:

Code: Select all

"C:\Program Files\Handbrake\HandBrakeCLI.exe" -i "C:\Users\zeddy\Desktop\test\test.avi" -t 1 --start-at frame:2000 --stop-at frame:1000 -o "C:\Users\zeddy\Desktop\test\test.t1.mp4"  -f mp4 --strict-anamorphic  -e x264 -q 20 --vfr  -a 1 -E faac -B 160 -6 dpl2 -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 -x ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0 --verbose=1

"start-at" looks ok (2000), but this part isn't correct: --stop-at frame:1000. It should be --stop-at frame:3000, no?

I tried it again using frames 1234 through 3456 and I got this:

Code: Select all

"C:\Program Files\Handbrake\HandBrakeCLI.exe" -i "C:\Users\zeddy\Desktop\test\test.avi" -t 1 --start-at frame:1234 --stop-at frame:2222 -o "C:\Users\zeddy\Desktop\test\test.t1.mp4"  -f mp4 --strict-anamorphic  -e x264 -q 20 --vfr  -a 1 -E faac -B 160 -6 dpl2 -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 -x ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0 --verbose=1

Now I get --stop-at frame:2222. It seems to be subtracting the two values, changing my stop frame number to a "duration" number.

It's the same when I export the queue:

Code: Select all

<Query> -i "C:\Users\zeddy\Desktop\test\test.avi" -t 1 --start-at frame:1234 --stop-at frame:2222 -o "C:\Users\zeddy\Desktop\test\test.t1.mp4"  -f mp4 --strict-anamorphic  -e x264 -q 20 --vfr  -a 1 -E faac -B 160 -6 dpl2 -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 -x ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0 --verbose=1</Query>

This seems contrary to the documentation, which states:

--start-at <unit:#> Start encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)
--stop-at <unit:#> Stop encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)

The documentation also implies that "duration" can only be measured in seconds, not frames.

Thanks.

[JohnAStebbins]

"start-at" looks ok (2000), but this part isn't correct: --stop-at frame:1000. It should be --stop-at frame:3000, no?

...


This seems contrary to the documentation, which states:

--start-at <unit:#> Start encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)
--stop-at <unit:#> Stop encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)

The documentation also implies that "duration" can only be measured in seconds, not frames.

Thanks.

The stop-at parameter is a duration in ticks (unit "pts"), seconds (unit "duration"), or frames (unit "frame"). Yes, the documentation sucks.

[zeddy]

The stop-at parameter is a duration in ticks (unit "pts"), seconds (unit "duration"), or frames (unit "frame"). Yes, the documentation sucks.

Aargh - I have wasted most of one day on this.

If it's duration, it should have been called "--stop-after"!

I'm assuming that the word "duration" should not be used in the description of "start-at". In other words, it means the absolute start frame, time or pts. After what I've just learned, there is perhaps no reason to make that assumption.

Documentation should be changed to read:

Code: Select all

--start-at <unit:#> Start encoding at a given frame, seconds or pts (on a 90kHz clock)
--stop-at <unit:#> Stop encoding after a given duration, measured in frames, seconds or pts (on a 90kHz clock)

and add this:

Code: Select all

Examples:
--start-at frame:1000 --stop-at seconds:60 (start at frame 1000 and stop after 60 seconds)
--start-at seconds:100 --stop-at frame: 500 (start at 100 seconds, and stop after 500 frames)

If there was freedom to change the interface, change "--stop-at" to "--stop-after" and perhaps change "pts" to "ticks"

Thanks.

[zeddy]

Well, I'm getting nowhere with my tests, trying to figure this out. I'm wasting a lot of time. Rather than speculate about the syntax and command-line usage, let me ask some specific questions. This is all about "--start-at" and "--stop-at", which is documented as:

Code: Select all

--start-at    <unit:#>  Start encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)
 --stop-at    <unit:#>   Stop encoding at a given frame, duration (in seconds), or pts (on a 90kHz clock)

My first problem is to find out what "unit" refers to. Perhaps it should be "frame" or "duration" (or "seconds") or "pts". First of all, I better find out what it does when it encounters an unrecognized unit name. So I tried running handbrakecli with the following:

Code: Select all

--start-at apples:100  --stop-at oranges:300

Here's the full command:

Code: Select all

"C:\Program Files\Handbrake\HandBrakeCLI.exe" -i "test.avi" -o "test.mp4" --start-at apples:29.0345 --stop-at oranges:14.3103 -f mp4 --strict-anamorphic  -e x264 -q 20 --vfr  -a 1 -E faac -B 160 -6 dpl2 -R Auto -D 0 --gain=0 --audio-copy-mask none --audio-fallback ffac3 -x ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0 --verbose=1

I should get some sort of error message concerning "apples" and "oranges", right? Nope, it just carried on and ended up doing the whole file. So what are the accepted units:

Code: Select all

--start-at frame:123
--start-at duration:123
--start-at seconds:123
--start-at pts:123

Can duration/seconds be floating-point (e.g. "26.45" seconds) or is it integer?

And what about "--stop-at", does it accept the same keywords for unit?

Here's what I'm trying to do. I have a test.avi file, and I'm trying to slice it up into about 100 pieces. I have the start time of each segment (hour, minute, second, frame). I calculate the end time of each segment from the start of the next segment.

[Tjabo]

Hi zeddy!

I don't claim to be a HB guru, but I've used the CLI some...

Regarding the units used for start-at and stop-at I've used frame: and duration:. I see no reason why pts: shouldn't work though I've yet to use it, but I can't remember seeing anything about seconds: in the documentation. And yes, stop-at accepts the same units.

The duration can be set to a fraction of a second. I did some tests and got the following results:

Code: Select all

--start-at duration:70 --stop-at duration:5
    Stream #0.0(eng): Video: mpeg2video (Main), yuv420p, 720x576 [PAR 64:45 DAR 16:9], 7500 kb/s, 25 fps, 25 tbr, 1k tbn, 50 tbc
    + title 1, start 0:1:10.00 stop 0:1:15.00
 mux: track 0, 125 frames, 582347 bytes, 924.36 kbps, fifo 128


--start-at duration:70 --stop-at duration:5.25
    Stream #0.0(eng): Video: mpeg2video (Main), yuv420p, 720x576 [PAR 64:45 DAR 16:9], 7500 kb/s, 25 fps, 25 tbr, 1k tbn, 50 tbc
    + title 1, start 0:1:10.00 stop 0:1:15.25
 mux: track 0, 132 frames, 620778 bytes, 940.57 kbps, fifo 256

I also tried mixing units by specifying the start in seconds and the stop in frames:

Code: Select all

 --start-at duration:70 --stop-at frame:125
    Stream #0.0(eng): Video: mpeg2video (Main), yuv420p, 720x576 [PAR 64:45 DAR 16:9], 7500 kb/s, 25 fps, 25 tbr, 1k tbn, 50 tbc
    + title 1, start 0:1:10.00 stop 0:1:10.00
 mux: track 0, 126 frames, 586400 bytes, 658.88 kbps, fifo 128


 --start-at duration:70 --stop-at frame:125
    Stream #0.0(eng): Video: mpeg2video (Main), yuv420p, 720x576 [PAR 64:45 DAR 16:9], 7500 kb/s, 25 fps, 25 tbr, 1k tbn, 50 tbc
    + title 1, start 0:1:10.00 stop 0:1:10.00
 mux: track 0, 151 frames, 688360 bytes, 658.72 kbps, fifo 256

As you can see it worked even though the start-stop information in the log didn't show the duration correctly.

This was done using 4846svn which is quite old by now.

I hope this answers at least some questions.

[zeddy]

Ok, I will accept that "seconds" is unrecognized and that "duration" means seconds, and "frame" means frame.

But I'm still having a problem extracting. It seems to be rounding my fractional seconds to whole seconds.

1. I watched the .avi file, and wrote down the start times of each slice (in minutes & seconds). For example:

Code: Select all

0	28.91 - skip this part
1	8.67
1	38.65
1	42.65
2	27.17
3	1.29
3	57.15

2. I subtracted the times to get the 'duration' in seconds and built my extraction script as follows:

Code: Select all

... --start-at duration:28.91 --stop-at duration:39.76 ...
... --start-at duration:68.67 --stop-at duration:29.98 ...
... --start-at duration:98.65 --stop-at duration:4 ...
... --start-at duration:102.65 --stop-at duration:44.52 ...
... --start-at duration:147.17 --stop-at duration:34.12 ...
... --start-at duration:181.29 --stop-at duration:55.86 ...
...

I ran the script and here's the activity log:

Code: Select all

[03:19:20] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:19:20] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (000000000031DDC0)
[03:19:20] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:19:20] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:19:21] No channel layout reported by Libav; guessing one from channel count.
[03:19:21] scan: decoding previews for title 1
[03:19:21] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:19:21] scan: title (0) job->width:624, job->height:480
Scanning title 1...
[03:19:21] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:19:21] 1 job(s) to process
[03:19:21] starting job
[03:19:21] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:19:21] sync: expecting 1198 video frames
[03:19:21] job configuration:
[03:19:21]  * source
[03:19:21]    + test.avi
[03:19:21]    + title 1, start 0:0:28.00 stop 0:1:7.00
[03:19:21]    + container: avi
[03:19:21]    + data rate: 30307 kbps
[03:19:21]  * destination
[03:19:21]    + test1.mp4
[03:19:21]    + container: MPEG-4 (.mp4 and .m4v)
[03:19:21]  * video track
[03:19:21]    + decoder: dvvideo
[03:19:21]      + bitrate 28771 kbps
[03:19:21]    + frame rate: same as source (around 29.970 fps)
[03:19:21]    + strict anamorphic
[03:19:21]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:19:21]      + pixel aspect ratio: 8 / 9
[03:19:21]      + display dimensions: 631 * 480
[03:19:21]    + encoder: H.264 (x264)
[03:19:21]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:19:21]      + quality: 20.00 (RF)
[03:19:21]  * audio track 1
[03:19:21]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:19:21]    + mixdown: Stereo
[03:19:21]    + encoder: AAC (faac)
[03:19:21]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:19:21] encx264: min-keyint: 30, keyint: 300
[03:19:21] encx264: encoding with stored aspect 8/9
[03:19:21] encx264: Encoding at constant RF 20.000000
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
[03:19:21] reader: first SCR 2519517 id 0x0 DTS 2519517
[03:19:32] sync: reached pts 3510507, exiting early
[03:19:33] work: average encoding speed for job is 103.010651 fps
[03:19:33] reader: done. 1 scr changes
[03:19:33] sync: got 1169 frames, 1198 expected
[03:19:33] dvvideo-decoder done: 1277 frames, 0 decoder errors, 0 drops
[03:19:34] render: lost time: 0 (0 frames)
[03:19:34] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:9     Avg QP:20.79  size: 37482  PSNR Mean Y:43.70 U:49.00 V:50.10 Avg:44.89 Global:43.75
x264 [info]: frame P:710   Avg QP:23.50  size: 16110  PSNR Mean Y:39.70 U:44.60 V:46.98 Avg:40.92 Global:40.32
x264 [info]: frame B:450   Avg QP:26.22  size:  7478  PSNR Mean Y:37.08 U:42.81 V:45.69 Avg:38.41 Global:38.30
x264 [info]: consecutive B-frames: 23.0% 77.0%  0.0%  0.0%
x264 [info]: mb I  I16..4: 29.8%  0.0% 70.2%
x264 [info]: mb P  I16..4: 13.8%  0.0%  2.2%  P16..4: 41.2% 20.2% 14.1%  0.0%  0.0%    skip: 8.6%
x264 [info]: mb B  I16..4:  1.3%  0.0%  0.2%  B16..8: 22.7%  9.1%  1.6%  direct:44.2%  skip:20.9%  L0:25.7% L1:42.0% BI:32.3%
x264 [info]: coded y,uvDC,uvAC intra: 46.5% 67.5% 17.6% inter: 43.5% 44.9% 0.4%
x264 [info]: i16 v,h,dc,p: 46% 27% 22%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 22% 36% 15%  4%  4%  5%  4%  5%  5%
x264 [info]: i8c dc,h,v,p: 55% 22% 19%  4%
x264 [info]: Weighted P-Frames: Y:27.5% UV:7.3%
x264 [info]: SSIM Mean Y:0.9551501 (13.482db)
x264 [info]: PSNR Mean Y:38.721 U:43.947 V:46.507 Avg:39.984 Global:39.445 kb/s:3105.35
[03:19:34] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:19:34] mux: track 0, 1169 frames, 15140471 bytes, 3105.29 kbps, fifo 1024
[03:19:34] mux: track 1, 1829 frames, 780501 bytes, 160.08 kbps, fifo 2048
[03:19:34] libhb: work result = 0

Encode done!
HandBrake has exited.
[03:19:34] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:19:34] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (0000000001C7DDC0)
[03:19:34] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:19:34] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:19:34] No channel layout reported by Libav; guessing one from channel count.
[03:19:34] scan: decoding previews for title 1
[03:19:34] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:19:34] scan: title (0) job->width:624, job->height:480
[03:19:34] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:19:34] 1 job(s) to process
[03:19:34] starting job
[03:19:34] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:19:34] sync: expecting 899 video frames
[03:19:34] job configuration:
[03:19:34]  * source
[03:19:34]    + test.avi
[03:19:34]    + title 1, start 0:1:8.00 stop 0:1:37.00
[03:19:34]    + container: avi
[03:19:34]    + data rate: 30307 kbps
[03:19:34]  * destination
[03:19:34]    + test2.mp4
[03:19:34]    + container: MPEG-4 (.mp4 and .m4v)
[03:19:34]  * video track
[03:19:34]    + decoder: dvvideo
[03:19:34]      + bitrate 28771 kbps
[03:19:34]    + frame rate: same as source (around 29.970 fps)
[03:19:34]    + strict anamorphic
[03:19:34]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:19:34]      + pixel aspect ratio: 8 / 9
[03:19:34]      + display dimensions: 631 * 480
[03:19:34]    + encoder: H.264 (x264)
[03:19:34]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:19:34]      + quality: 20.00 (RF)
[03:19:34]  * audio track 1
[03:19:34]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:19:34]    + mixdown: Stereo
[03:19:34]    + encoder: AAC (faac)
[03:19:34]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:19:34] reader: first SCR 6120114 id 0x0 DTS 6120114
[03:19:34] encx264: min-keyint: 30, keyint: 300
[03:19:34] encx264: encoding with stored aspect 8/9
[03:19:34] encx264: Encoding at constant RF 20.000000
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
[03:19:43] sync: reached pts 2612610, exiting early
[03:19:44] work: average encoding speed for job is 95.476242 fps
[03:19:44] reader: done. 1 scr changes
[03:19:44] sync: got 870 frames, 899 expected
[03:19:44] dvvideo-decoder done: 998 frames, 0 decoder errors, 0 drops
[03:19:44] render: lost time: 0 (0 frames)
[03:19:44] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:7     Avg QP:21.92  size: 47328  PSNR Mean Y:41.30 U:46.35 V:47.50 Avg:42.49 Global:41.99
x264 [info]: frame P:456   Avg QP:24.41  size: 23923  PSNR Mean Y:37.91 U:43.01 V:45.26 Avg:39.15 Global:39.01
x264 [info]: frame B:407   Avg QP:26.22  size: 10353  PSNR Mean Y:36.85 U:42.48 V:45.13 Avg:38.16 Global:37.93
x264 [info]: consecutive B-frames:  9.2% 82.5%  8.3%  0.0%
x264 [info]: mb I  I16..4: 17.5%  0.0% 82.5%
x264 [info]: mb P  I16..4:  7.5%  0.0%  4.7%  P16..4: 40.7% 24.2% 20.3%  0.0%  0.0%    skip: 2.5%
x264 [info]: mb B  I16..4:  1.1%  0.0%  0.3%  B16..8: 23.5% 10.6%  3.3%  direct:44.2%  skip:16.9%  L0:24.6% L1:41.1% BI:34.3%
x264 [info]: coded y,uvDC,uvAC intra: 69.2% 68.8% 18.7% inter: 54.2% 48.0% 0.8%
x264 [info]: i16 v,h,dc,p: 29% 31% 33%  7%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16% 38% 16%  4%  4%  4%  6%  4%  8%
x264 [info]: i8c dc,h,v,p: 56% 20% 19%  5%
x264 [info]: Weighted P-Frames: Y:30.0% UV:8.3%
x264 [info]: SSIM Mean Y:0.9536898 (13.343db)
x264 [info]: PSNR Mean Y:37.438 U:42.792 V:45.215 Avg:38.716 Global:38.487 kb/s:4258.83
[03:19:44] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:19:44] mux: track 0, 870 frames, 15453431 bytes, 4258.76 kbps, fifo 1024
[03:19:44] mux: track 1, 1361 frames, 580548 bytes, 159.99 kbps, fifo 2048
[03:19:45] libhb: work result = 0

Encode done!
HandBrake has exited.
[03:19:45] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:19:45] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (000000000031DE20)
[03:19:45] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:19:45] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:19:45] No channel layout reported by Libav; guessing one from channel count.
[03:19:45] scan: decoding previews for title 1
[03:19:45] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:19:45] scan: title (0) job->width:624, job->height:480
[03:19:45] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:19:45] 1 job(s) to process
[03:19:45] starting job
[03:19:45] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:19:45] sync: expecting 149 video frames
[03:19:45] job configuration:
[03:19:45]  * source
[03:19:45]    + test.avi
[03:19:45]    + title 1, start 0:1:38.00 stop 0:1:42.00
[03:19:45]    + container: avi
[03:19:45]    + data rate: 30307 kbps
[03:19:45]  * destination
[03:19:45]    + test3.mp4
[03:19:45]    + container: MPEG-4 (.mp4 and .m4v)
[03:19:45]  * video track
[03:19:45]    + decoder: dvvideo
[03:19:45]      + bitrate 28771 kbps
[03:19:45]    + frame rate: same as source (around 29.970 fps)
[03:19:45]    + strict anamorphic
[03:19:45]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:19:45]      + pixel aspect ratio: 8 / 9
[03:19:45]      + display dimensions: 631 * 480
[03:19:45]    + encoder: H.264 (x264)
[03:19:45]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:19:45]      + quality: 20.00 (RF)
[03:19:45]  * audio track 1
[03:19:45]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:19:45]    + mixdown: Stereo
[03:19:45]    + encoder: AAC (faac)
[03:19:45]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:19:45] reader: first SCR 8819811 id 0x0 DTS 8819811
[03:19:45] encx264: min-keyint: 30, keyint: 300
[03:19:45] encx264: encoding with stored aspect 8/9
[03:19:45] encx264: Encoding at constant RF 20.000000
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
[03:19:46] sync: reached pts 360360, exiting early
[03:19:46] work: average encoding speed for job is 0.000000 fps
[03:19:47] reader: done. 1 scr changes
[03:19:47] sync: got 120 frames, 149 expected
[03:19:47] dvvideo-decoder done: 258 frames, 0 decoder errors, 0 drops
[03:19:47] render: lost time: 0 (0 frames)
[03:19:47] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:2     Avg QP:23.74  size: 44924  PSNR Mean Y:40.17 U:44.50 V:46.28 Avg:41.29 Global:40.71
x264 [info]: frame P:71    Avg QP:23.48  size: 10936  PSNR Mean Y:40.49 U:44.33 V:46.80 Avg:41.59 Global:41.04
x264 [info]: frame B:47    Avg QP:24.92  size:  7094  PSNR Mean Y:39.16 U:43.24 V:46.13 Avg:40.30 Global:39.50
x264 [info]: consecutive B-frames: 27.5% 55.0% 17.5%  0.0%
x264 [info]: mb I  I16..4:  9.2%  0.0% 90.8%
x264 [info]: mb P  I16..4: 17.8%  0.0%  4.7%  P16..4: 42.9% 17.8%  8.9%  0.0%  0.0%    skip: 7.9%
x264 [info]: mb B  I16..4:  2.6%  0.0%  1.2%  B16..8: 26.1%  9.2%  1.9%  direct:37.3%  skip:21.8%  L0:31.7% L1:46.8% BI:21.4%
x264 [info]: coded y,uvDC,uvAC intra: 51.1% 69.1% 19.5% inter: 29.5% 52.1% 0.8%
x264 [info]: i16 v,h,dc,p: 42% 25% 23%  9%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 16% 42% 13%  5%  4%  4%  6%  5%  6%
x264 [info]: i8c dc,h,v,p: 55% 20% 19%  5%
x264 [info]: Weighted P-Frames: Y:33.8% UV:8.5%
x264 [info]: SSIM Mean Y:0.9498039 (12.993db)
x264 [info]: PSNR Mean Y:39.963 U:43.907 V:46.530 Avg:41.083 Global:40.367 kb/s:2397.07
[03:19:47] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:19:48] mux: track 0, 120 frames, 1199647 bytes, 2396.90 kbps, fifo 128
[03:19:48] mux: track 1, 188 frames, 80284 bytes, 160.41 kbps, fifo 256
[03:19:48] libhb: work result = 0

Encode done!
HandBrake has exited.
[03:19:48] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:19:48] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (0000000001D4DDC0)
[03:19:48] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:19:48] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:19:48] No channel layout reported by Libav; guessing one from channel count.
[03:19:48] scan: decoding previews for title 1
[03:19:48] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:19:48] scan: title (0) job->width:624, job->height:480
[03:19:48] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:19:48] 1 job(s) to process
[03:19:48] starting job
[03:19:48] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:19:48] sync: expecting 1348 video frames
[03:19:48] job configuration:
[03:19:48]  * source
[03:19:48]    + test.avi
[03:19:48]    + title 1, start 0:1:42.00 stop 0:2:26.00
[03:19:48]    + container: avi
[03:19:48]    + data rate: 30307 kbps
[03:19:48]  * destination
[03:19:48]    + test4.mp4
[03:19:48]    + container: MPEG-4 (.mp4 and .m4v)
[03:19:48]  * video track
[03:19:48]    + decoder: dvvideo
[03:19:48]      + bitrate 28771 kbps
[03:19:48]    + frame rate: same as source (around 29.970 fps)
[03:19:48]    + strict anamorphic
[03:19:48]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:19:48]      + pixel aspect ratio: 8 / 9
[03:19:48]      + display dimensions: 631 * 480
[03:19:48]    + encoder: H.264 (x264)
[03:19:48]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:19:48]      + quality: 20.00 (RF)
[03:19:48]  * audio track 1
[03:19:48]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:19:48]    + mixdown: Stereo
[03:19:48]    + encoder: AAC (faac)
[03:19:48]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:19:48] reader: first SCR 9180171 id 0x0 DTS 9180171
[03:19:48] encx264: min-keyint: 30, keyint: 300
[03:19:48] encx264: encoding with stored aspect 8/9
[03:19:48] encx264: Encoding at constant RF 20.000000
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
[03:20:01] sync: reached pts 3960957, exiting early
[03:20:02] work: average encoding speed for job is 96.570328 fps
[03:20:02] reader: done. 1 scr changes
[03:20:03] sync: got 1319 frames, 1348 expected
[03:20:03] dvvideo-decoder done: 1442 frames, 0 decoder errors, 0 drops
[03:20:03] render: lost time: 0 (0 frames)
[03:20:03] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:9     Avg QP:22.24  size: 50975  PSNR Mean Y:41.05 U:45.74 V:47.05 Avg:42.21 Global:42.01
x264 [info]: frame P:701   Avg QP:24.51  size: 22529  PSNR Mean Y:37.92 U:42.58 V:45.09 Avg:39.13 Global:38.82
x264 [info]: frame B:609   Avg QP:26.64  size:  9113  PSNR Mean Y:36.60 U:41.87 V:44.78 Avg:37.88 Global:37.50
x264 [info]: consecutive B-frames: 10.9% 79.3%  9.8%  0.0%
x264 [info]: mb I  I16..4: 15.9%  0.0% 84.1%
x264 [info]: mb P  I16..4: 10.4%  0.0%  3.5%  P16..4: 43.0% 22.2% 17.4%  0.0%  0.0%    skip: 3.6%
x264 [info]: mb B  I16..4:  1.8%  0.0%  0.2%  B16..8: 24.1% 10.6%  2.0%  direct:42.2%  skip:18.9%  L0:27.1% L1:39.8% BI:33.1%
x264 [info]: coded y,uvDC,uvAC intra: 63.6% 67.7% 21.5% inter: 50.5% 50.4% 1.0%
x264 [info]: i16 v,h,dc,p: 43% 26% 25%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 31% 33% 15%  3%  3%  4%  3%  5%  4%
x264 [info]: i8c dc,h,v,p: 50% 20% 25%  4%
x264 [info]: Weighted P-Frames: Y:37.1% UV:9.6%
x264 [info]: SSIM Mean Y:0.9553582 (13.503db)
x264 [info]: PSNR Mean Y:37.334 U:42.275 V:44.960 Avg:38.575 Global:38.174 kb/s:3962.93
[03:20:03] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:20:03] mux: track 0, 1319 frames, 21801040 bytes, 3962.87 kbps, fifo 1024
[03:20:03] mux: track 1, 2063 frames, 880208 bytes, 160.00 kbps, fifo 2048
[03:20:03] libhb: work result = 0

Encode done!
HandBrake has exited.
[03:20:03] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:20:03] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (0000000001BFDDC0)
[03:20:03] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:20:03] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:20:03] No channel layout reported by Libav; guessing one from channel count.
[03:20:03] scan: decoding previews for title 1
[03:20:03] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:20:03] scan: title (0) job->width:624, job->height:480
[03:20:03] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:20:03] 1 job(s) to process
[03:20:03] starting job
[03:20:03] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:20:03] sync: expecting 1048 video frames
[03:20:03] job configuration:
[03:20:03]  * source
[03:20:03]    + test.avi
[03:20:03]    + title 1, start 0:2:27.00 stop 0:3:1.00
[03:20:03]    + container: avi
[03:20:03]    + data rate: 30307 kbps
[03:20:03]  * destination
[03:20:03]    + test5.mp4
[03:20:03]    + container: MPEG-4 (.mp4 and .m4v)
[03:20:03]  * video track
[03:20:03]    + decoder: dvvideo
[03:20:03]      + bitrate 28771 kbps
[03:20:03]    + frame rate: same as source (around 29.970 fps)
[03:20:03]    + strict anamorphic
[03:20:03]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:20:03]      + pixel aspect ratio: 8 / 9
[03:20:03]      + display dimensions: 631 * 480
[03:20:03]    + encoder: H.264 (x264)
[03:20:03]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:20:03]      + quality: 20.00 (RF)
[03:20:03]  * audio track 1
[03:20:03]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:20:03]    + mixdown: Stereo
[03:20:03]    + encoder: AAC (faac)
[03:20:03]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:20:03] reader: first SCR 13231218 id 0x0 DTS 13231218
[03:20:03] encx264: min-keyint: 30, keyint: 300
[03:20:03] encx264: encoding with stored aspect 8/9
[03:20:03] encx264: Encoding at constant RF 20.000000
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
[03:20:13] sync: reached pts 3060057, exiting early
[03:20:13] work: average encoding speed for job is 110.253456 fps
[03:20:14] reader: done. 1 scr changes
[03:20:14] sync: got 1019 frames, 1048 expected
[03:20:14] dvvideo-decoder done: 1154 frames, 0 decoder errors, 0 drops
[03:20:14] render: lost time: 0 (0 frames)
[03:20:14] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:10    Avg QP:20.68  size: 31183  PSNR Mean Y:42.74 U:47.53 V:48.68 Avg:43.89 Global:43.66
x264 [info]: frame P:726   Avg QP:22.89  size: 10825  PSNR Mean Y:40.53 U:45.10 V:47.54 Avg:41.73 Global:41.38
x264 [info]: frame B:283   Avg QP:25.63  size:  6655  PSNR Mean Y:37.66 U:43.35 V:46.45 Avg:38.99 Global:38.80
x264 [info]: consecutive B-frames: 46.0% 49.3%  4.7%  0.0%
x264 [info]: mb I  I16..4: 46.2%  0.0% 53.8%
x264 [info]: mb P  I16..4: 22.7%  0.0%  1.2%  P16..4: 41.9% 14.8%  7.8%  0.0%  0.0%    skip:11.6%
x264 [info]: mb B  I16..4:  2.9%  0.0%  0.2%  B16..8: 24.5%  8.9%  1.0%  direct:40.7%  skip:21.7%  L0:27.7% L1:46.0% BI:26.2%
x264 [info]: coded y,uvDC,uvAC intra: 36.4% 66.2% 14.5% inter: 33.6% 45.8% 0.2%
x264 [info]: i16 v,h,dc,p: 44% 28% 21%  7%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 20% 36% 19%  3%  3%  4%  4%  4%  6%
x264 [info]: i8c dc,h,v,p: 57% 21% 19%  3%
x264 [info]: Weighted P-Frames: Y:18.6% UV:5.5%
x264 [info]: SSIM Mean Y:0.9525602 (13.239db)
x264 [info]: PSNR Mean Y:39.757 U:44.640 V:47.252 Avg:40.987 Global:40.512 kb/s:2365.62
[03:20:14] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:20:14] mux: track 0, 1019 frames, 10053676 bytes, 2363.21 kbps, fifo 1024
[03:20:14] mux: track 1, 1596 frames, 680831 bytes, 160.04 kbps, fifo 2048
[03:20:14] libhb: work result = 0

Encode done!
HandBrake has exited.
[03:20:14] hb_init: starting libhb thread
HandBrake 0.9.8 (2012071700) - MinGW x86_64 - http://handbrake.fr
4 CPUs detected
Opening test.avi...
[03:20:14] hb_scan: path=test.avi, title_index=1
libbluray/bdnav/index_parse.c:157: indx_parse(): error opening test.avi/BDMV/index.bdmv
libbluray/bluray.c:1471: nav_get_title_list(test.avi) failed (0000000001BFDDC0)
[03:20:14] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdnav:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[03:20:14] dvd: not a dvd - trying as a stream/file instead
Input #0, avi, from 'test.avi':
  Duration: 01:05:13.10, start: 0.000000, bitrate: 30307 kb/s
    Stream #0.0: Video: dvvideo, yuv411p, 720x480, 28771 kb/s, PAR 8:9 DAR 4:3, 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: pcm_s16le, 48000 Hz, 2 channels, s16, 1536 kb/s
[03:20:14] No channel layout reported by Libav; guessing one from channel count.
[03:20:14] scan: decoding previews for title 1
[03:20:14] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/6/4, aspect 4:3, PAR 8:9
[03:20:14] scan: title (0) job->width:624, job->height:480
[03:20:14] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: test.avi
  + duration: 01:05:13
  + size: 720x480, pixel aspect: 8/9, display aspect: 1.33, 29.970 fps
  + autocrop: 0/0/6/4
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:05:13
  + audio tracks:
    + 1, Unknown (pcm_s16le) (2.0 ch) (iso639-2: und)
  + subtitle tracks:
[03:20:15] 1 job(s) to process
[03:20:15] starting job
[03:20:15] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[03:20:15] sync: expecting 1678 video frames
[03:20:15] job configuration:
[03:20:15]  * source
[03:20:15]    + test.avi
[03:20:15]    + title 1, start 0:3:1.00 stop 0:3:56.00
[03:20:15]    + container: avi
[03:20:15]    + data rate: 30307 kbps
[03:20:15]  * destination
[03:20:15]    + test6.mp4
[03:20:15]    + container: MPEG-4 (.mp4 and .m4v)
[03:20:15]  * video track
[03:20:15]    + decoder: dvvideo
[03:20:15]      + bitrate 28771 kbps
[03:20:15]    + frame rate: same as source (around 29.970 fps)
[03:20:15]    + strict anamorphic
[03:20:15]      + storage dimensions: 720 * 480 -> 710 * 480, crop 0/0/6/4, mod 0
[03:20:15]      + pixel aspect ratio: 8 / 9
[03:20:15]      + display dimensions: 631 * 480
[03:20:15]    + encoder: H.264 (x264)
[03:20:15]      + options: ref=1:weightp=1:subq=2:rc-lookahead=10:trellis=0:8x8dct=0
[03:20:15]      + quality: 20.00 (RF)
[03:20:15]  * audio track 1
[03:20:15]    + decoder: Unknown (pcm_s16le) (2.0 ch) (track 1, id 0x1)
[03:20:15]    + mixdown: Stereo
[03:20:15]    + encoder: AAC (faac)
[03:20:15]      + bitrate: 160 kbps, samplerate: 48000 Hz
[03:20:15] reader: first SCR 16291275 id 0x0 DTS 16291275
[03:20:15] encx264: min-keyint: 30, keyint: 300
[03:20:15] encx264: encoding with stored aspect 8/9
[03:20:15] encx264: Encoding at constant RF 20.000000
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
[03:20:29] sync: reached pts 4951947, exiting early
[03:20:29] work: average encoding speed for job is 115.336609 fps
[03:20:29] reader: done. 1 scr changes
[03:20:30] sync: got 1649 frames, 1678 expected
[03:20:30] dvvideo-decoder done: 1764 frames, 0 decoder errors, 0 drops
[03:20:30] render: lost time: 0 (0 frames)
[03:20:30] render: gained time: 0 (0 frames) (0 not accounted for)
x264 [info]: frame I:23    Avg QP:20.40  size: 25872  PSNR Mean Y:43.00 U:47.31 V:48.68 Avg:44.11 Global:43.97
x264 [info]: frame P:1531  Avg QP:23.03  size:  8437  PSNR Mean Y:40.61 U:44.89 V:47.32 Avg:41.78 Global:41.73
x264 [info]: frame B:95    Avg QP:24.30  size:  5068  PSNR Mean Y:39.27 U:43.74 V:46.61 Avg:40.47 Global:40.43
x264 [info]: consecutive B-frames: 88.5% 11.5%  0.0%  0.0%
x264 [info]: mb I  I16..4: 65.5%  0.0% 34.5%
x264 [info]: mb P  I16..4: 35.5%  0.0%  1.4%  P16..4: 40.8% 11.0%  5.2%  0.0%  0.0%    skip: 6.1%
x264 [info]: mb B  I16..4:  4.9%  0.0%  0.3%  B16..8: 28.6%  8.7%  1.0%  direct:35.3%  skip:21.3%  L0:30.5% L1:47.7% BI:21.8%
x264 [info]: coded y,uvDC,uvAC intra: 41.1% 81.4% 28.6% inter: 27.8% 60.6% 0.3%
x264 [info]: i16 v,h,dc,p: 34% 30% 28%  8%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 20% 42% 19%  3%  3%  3%  3%  3%  4%
x264 [info]: i8c dc,h,v,p: 51% 26% 19%  4%
x264 [info]: Weighted P-Frames: Y:8.9% UV:0.5%
x264 [info]: SSIM Mean Y:0.9502844 (13.035db)
x264 [info]: PSNR Mean Y:40.569 U:44.860 V:47.300 Avg:41.734 Global:41.671 kb/s:2034.55
[03:20:30] pcm_s16le-decoder done: 0 frames, 0 decoder errors, 0 drops
[03:20:30] mux: track 0, 1649 frames, 13992236 bytes, 2034.43 kbps, fifo 2048
[03:20:30] mux: track 1, 2580 frames, 1100704 bytes, 160.04 kbps, fifo 2048
[03:20:30] libhb: work result = 0

Encode done!
HandBrake has exited.

The extracted segments are close, but not close enough. Some of them are a bit longer than they should be, going into the next segment. Some of them seem to start too soon, incorporating some of the previous segment. The amount is no more than a second or so, that's what I mean by 'close'.

Looking at the activity log, I see this:

Code: Select all

[03:19:21]    + title 1, start 0:0:28.00 stop 0:1:7.00
[03:19:34]    + title 1, start 0:1:8.00 stop 0:1:37.00
[03:19:45]    + title 1, start 0:1:38.00 stop 0:1:42.00
[03:19:48]    + title 1, start 0:1:42.00 stop 0:2:26.00
[03:20:03]    + title 1, start 0:2:27.00 stop 0:3:1.00
[03:20:15]    + title 1, start 0:3:1.00 stop 0:3:56.00

This is really screwy, maddening and frustrating. I have been told that "--stop-at" is the DURATION or LENGTH of the segment to be extracted. But the activity log displays the actual stop time. If it wanted the stop time (not duration/length) I could have supplied it!

Comparing to the 2nd code block above (command-line requested start/stop times), you can see it has ignored my fractional times, truncating to whole seconds. For example:
(a) I specified start time=28.91 s, length=39.76, but it used start time=28 s, stop at 67 s, for a length of 39
(b) I specified start time=68.67 s, length=29.98, but it used start time=68 s, stop at 97 s, for a length of 29

So why does it round the start time and duration/length to integer seconds?!

More to the point, why did Tjabo's test preserve the fractional part of the --stop-at duration" and my test did not?

--start-at duration:70 --stop-at duration:5.25
Stream #0.0(eng): Video: mpeg2video (Main), yuv420p, 720x576 [PAR 64:45 DAR 16:9], 7500 kb/s, 25 fps, 25 tbr, 1k tbn, 50 tbc
+ title 1, start 0:1:10.00 stop 0:1:15.25

The rest of the activity log doesn't mean much to me, but that's why I'm posting here in the tech help section WITH the required activity log. Hopefully one of the experts can explain what is going on.

Perhaps I'll have better luck using frames, which are integer values. The properties of test.avi indicate it is 29.970 fps, and that's what also appears in the activity log. So maybe I should calculate integer frames from my table of times.

Thanks for your time.

[Tjabo]

More to the point, why did Tjabo's test preserve the fractional part of the --stop-at duration" and my test did not?

Perhaps it's the source? My source was a MKV file containing a MPEG2 video stream. It was created using MakeMKV and taken from the extras on the Robots DVD.

Code: Select all

General           : Y:\Shared Videos\DVD Ripps\ROBOTS\TestPack\title01.mkv
Format            : Matroska at 3 873 Kbps
Length            : 137 MiB for 4mn 56s 680ms

Video #0          : MPEG Video at 3 412 Kbps
Aspect            : 720 x 576 (1.778) at 25.000 fps

Audio #0          : AC-3 at 192 Kbps
Infos             : 2 channels, 48.0 KHz
Language          : en

Audio #1          : AC-3 at 192 Kbps
Infos             : 2 channels, 48.0 KHz
Language          : sv

[zeddy]

I can't imagine the type of source file would cause this problem.

I have solved my problem by using integer frames instead of decimal seconds. I converted my table of minutes & seconds to frames (using 29.97 fps as indicated by handbrakecli activity log and also by file properties in Windows). It worked fine, and I was able to extract 135 slices of the file.

If any developers are reading this, may I recommend:

• clarify the documentation for "--start-at" and "stop-at", e.g. "--stop-at" is a DURATION or LENGTH and not a STOP POINT (consider renaming "--stop-at" to "--stop-after" to emphasize the usage)
  
  You could also use this syntax:
  --stop-at frames:123 - this means stop at frame #123 (an absolute stopping point)
  --stop-at frames:+123 - this means stop AFTER 123 frames
• clarify the documentation for the recognized keywords for <unit>, e.g. "frame", "duration" and "pts" (I don't even know what "pts" is). I would change the keywords to "time" instead of "duration" and indicate that the units of "time" are seconds. Usage would be:
  --start-at time:10.34 - start at 10.34 seconds
  --stop-at time:5.06- stop after 5.06 seconds
  
  or
  
  --start-at frame:123 - start at frame 123
  --stop-at frame:500 - stop after 500 seconds
• add in a check for correct units on the command-line (for example, I used apples:123 and it didn't give an error). Rejecting an unrecognized keyword would help solve problems like using "frames:123" and wondering why it was ignored (because the correct keyword is "frame", not "frames")
• examine the code to determine why it is truncated the decimal seconds supplied on the command-line

Thanks for all of the help on this.