I spotted this post in the Unix support, looks like he's describing the same thing.
I have come up with what I think is a minimal command line that demonstrates the bahaviour:
I can try again using the preset.json file if you want.
I can't find any log files, but here is the command output. I set --verbose, hope it's enough.
Code: Select all
$ HandBrakeCLI --verbose --audio-lang-list 'und' --all-audio -i . -o ../audio_test.mkv
[14:33:36] hb_init: starting libhb thread
[14:33:36] thread 7fb160b67700 started ("libhb")
HandBrake 20170109190729-6dca3af-master (2017011001) - Linux x86_64 - https://handbrake.fr
4 CPUs detected
Opening ....
[14:33:36] CPU: Intel(R) Core(TM) i5-3470 CPU @ 3.20GHz
[14:33:36] - Intel microarchitecture Ivy Bridge
[14:33:36] - logical processor count: 4
[14:33:36] OpenCL: library not available
[14:33:36] hb_scan: path=., title_index=1
disc.c:352: error opening file BDMV/index.bdmv
disc.c:352: error opening file BDMV/BACKUP/index.bdmv
[14:33:36] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
[14:33:36] scan: DVD has 1 title(s)
[14:33:36] scan: scanning title 1
[14:33:36] scan: opening IFO for VTS 1
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:683 ***
*** for vtsi_mat->vtsi_last_sector*2 <= vtsi_mat->vts_last_sector ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:1683 ***
*** for c_adt->cell_adr_table[i].vob_id <= c_adt->nr_of_vobs ***
[14:33:36] scan: duration is 00:41:30 (2490440 ms)
[14:33:36] pgc_id: 1, pgn: 1: pgc: 0x7fb158004c20
[14:33:36] scan: vts=1, ttn=1, cells=0->11, blocks=0->899406, 899407 blocks
[14:33:36] scan: checking audio 1
[14:33:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[14:33:36] scan: checking audio 2
[14:33:36] scan: id=0x81bd, lang=Francais (AC3), 3cc=fra ext=0
[14:33:36] scan: checking audio 3
[14:33:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=3
[14:33:36] scan: checking subtitle 1
[14:33:36] scan: id=0x20bd, lang=Nederlands, 3cc=nld ext=0
[14:33:36] scan: checking subtitle 2
[14:33:36] scan: id=0x21bd, lang=English, 3cc=eng ext=0
[14:33:36] scan: checking subtitle 3
[14:33:36] scan: id=0x22bd, lang=Francais, 3cc=fra ext=0
[14:33:36] scan: checking subtitle 4
[14:33:36] scan: id=0x23bd, lang=Francais, 3cc=fra ext=0
[14:33:36] scan: title 1 has 12 chapters
[14:33:36] scan: chap 1 c=0->0, b=0->42738 (42739), 133400 ms
[14:33:36] scan: chap 2 c=1->1, b=42739->63629 (20891), 49720 ms
[14:33:36] scan: chap 3 c=2->2, b=63630->248403 (184774), 444280 ms
[14:33:36] scan: chap 4 c=3->3, b=248404->282914 (34511), 95160 ms
[14:33:36] scan: chap 5 c=4->4, b=282915->357083 (74169), 181960 ms
[14:33:36] scan: chap 6 c=5->5, b=357084->435332 (78249), 209760 ms
[14:33:36] scan: chap 7 c=6->6, b=435333->469999 (34667), 96840 ms
[14:33:36] scan: chap 8 c=7->7, b=470000->555388 (85389), 259320 ms
[14:33:36] scan: chap 9 c=8->8, b=555389->741330 (185942), 540880 ms
[14:33:36] scan: chap 10 c=9->9, b=741331->782378 (41048), 111320 ms
[14:33:36] scan: chap 11 c=10->10, b=782379->888581 (106203), 324080 ms
[14:33:36] scan: chap 12 c=11->11, b=888582->899406 (10825), 43720 ms
[14:33:36] scan: aspect = 4:3
[14:33:36] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:683 ***
*** for vtsi_mat->vtsi_last_sector*2 <= vtsi_mat->vts_last_sector ***
[14:33:36] scan: title angle(s) 1
[ac3 @ 0x7fb15803a4e0] frame sync error
[14:33:36] scan: audio 0x80bd: ac3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch) (Dolby Surround)
[ac3 @ 0x7fb1580ae120] frame sync error
[14:33:36] scan: audio 0x82bd: ac3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (2.0 ch) (Dolby Surround)
[ac3 @ 0x7fb1580ae100] frame sync error
[14:33:36] scan: audio 0x81bd: ac3, rate=48000Hz, bitrate=192000 Francais (AC3) (2.0 ch) (Dolby Surround)
Scanning title 1 of 1, preview 9, 90.00 %[14:33:36] scan: 10 previews, 720x576, 25.000 fps, autocrop = 0/0/6/2, aspect 4:3, PAR 16:15
[14:33:36] libhb: scan thread found 1 valid title(s)
+ Using preset: CLI Default
+ title 1:
+ vts 1, ttn 1, cells 0->11 (899407 blocks)
+ duration: 00:41:30
+ size: 720x576, pixel aspect: 16/15, display aspect: 1.33, 25.000 fps
+ autocrop: 0/0/6/2
+ support opencl: no
+ chapters:
+ 1: cells 0->0, 42739 blocks, duration 00:02:13
+ 2: cells 1->1, 20891 blocks, duration 00:00:50
+ 3: cells 2->2, 184774 blocks, duration 00:07:24
+ 4: cells 3->3, 34511 blocks, duration 00:01:35
+ 5: cells 4->4, 74169 blocks, duration 00:03:02
+ 6: cells 5->5, 78249 blocks, duration 00:03:30
+ 7: cells 6->6, 34667 blocks, duration 00:01:37
+ 8: cells 7->7, 85389 blocks, duration 00:04:19
+ 9: cells 8->8, 185942 blocks, duration 00:09:01
+ 10: cells 9->9, 41048 blocks, duration 00:01:51
+ 11: cells 10->10, 106203 blocks, duration 00:05:24
+ 12: cells 11->11, 10825 blocks, duration 00:00:44
+ audio tracks:
+ 1, English (AC3) (2.0 ch) (Dolby Surround) (iso639-2: eng), 48000Hz, 192000bps
+ 2, Francais (AC3) (2.0 ch) (Dolby Surround) (iso639-2: fra), 48000Hz, 192000bps
+ 3, English (AC3) (Director's Commentary 1) (2.0 ch) (Dolby Surround) (iso639-2: eng), 48000Hz, 192000bps
+ subtitle tracks:
+ 1, Nederlands (iso639-2: nld) (Bitmap)(VOBSUB)
+ 2, English (iso639-2: eng) (Bitmap)(VOBSUB)
+ 3, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
+ 4, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
[14:33:36] 1 job(s) to process
[14:33:36] json job:
{
"Audio": {
"AudioList": [
{
"Bitrate": 128,
"CompressionLevel": -1.0,
"DRC": 0.0,
"DitherMethod": "auto",
"Encoder": "av_aac",
"Gain": 0.0,
"Mixdown": "dpl1",
"NormalizeMixLevel": false,
"PresetEncoder": "av_aac",
"Quality": -3.0,
"Samplerate": "auto",
"Track": 0
},
{
"Bitrate": 128,
"CompressionLevel": -1.0,
"DRC": 0.0,
"DitherMethod": "auto",
"Encoder": "av_aac",
"Gain": 0.0,
"Mixdown": "dpl1",
"NormalizeMixLevel": false,
"PresetEncoder": "av_aac",
"Quality": -3.0,
"Samplerate": "auto",
"Track": 1
}
],
"CopyMask": [
"copy:aac",
"copy:ac3",
"copy:eac3",
"copy:dtshd",
"copy:dts",
"copy:mp3",
"copy:truehd",
"copy:flac"
],
"FallbackEncoder": "av_aac"
},
"Destination": {
"ChapterList": [
{
"Name": "Chapter 1"
},
{
"Name": "Chapter 2"
},
{
"Name": "Chapter 3"
},
{
"Name": "Chapter 4"
},
{
"Name": "Chapter 5"
},
{
"Name": "Chapter 6"
},
{
"Name": "Chapter 7"
},
{
"Name": "Chapter 8"
},
{
"Name": "Chapter 9"
},
{
"Name": "Chapter 10"
},
{
"Name": "Chapter 11"
},
{
"Name": "Chapter 12"
}
],
"ChapterMarkers": true,
"File": "../audio_test.mkv",
"Mp4Options": {
"IpodAtom": false,
"Mp4Optimize": false
},
"Mux": "mkv"
},
"Filters": {
"FilterList": [
{
"ID": 6,
"Settings": {
"mode": 0
}
},
{
"ID": 11,
"Settings": {
"crop-bottom": 0,
"crop-left": 6,
"crop-right": 2,
"crop-top": 0,
"height": 576,
"width": 712
}
}
]
},
"Metadata": {},
"PAR": {
"Den": 15,
"Num": 16
},
"SequenceID": 0,
"Source": {
"Angle": 0,
"Path": ".",
"Range": {
"End": 12,
"Start": 1,
"Type": "chapter"
},
"Title": 1
},
"Subtitle": {
"Search": {
"Burn": true,
"Default": false,
"Enable": false,
"Forced": false
},
"SubtitleList": []
},
"Video": {
"ColorMatrixCode": 0,
"Encoder": "x264",
"Level": "auto",
"OpenCL": false,
"Options": "",
"Preset": "medium",
"Profile": "auto",
"QSV": {
"AsyncDepth": 4,
"Decode": false
},
"Quality": 22.0,
"Tune": "",
"Turbo": false,
"TwoPass": false
}
}
[14:33:36] starting job
[14:33:36] job configuration:
[14:33:36] * source
[14:33:36] + .
[14:33:36] + title 1, chapter(s) 1 to 12
[14:33:36] * destination
[14:33:36] + ../audio_test.mkv
[14:33:36] + container: Matroska (libavformat)
[14:33:36] + chapter markers
[14:33:36] * video track
[14:33:36] + decoder: mpeg2video
[14:33:36] + bitrate 7500 kbps
[14:33:36] + filters
[14:33:36] + Framerate Shaper (mode=0)
[14:33:36] + frame rate: same as source (around 25.000 fps)
[14:33:36] + Crop and Scale (width=712:height=576:crop-top=0:crop-bottom=0:crop-left=6:crop-right=2)
[14:33:36] + source: 720 * 576, crop (0/0/6/2): 712 * 576, scale: 712 * 576
[14:33:36] + Output geometry
[14:33:36] + storage dimensions: 712 x 576
[14:33:36] + pixel aspect ratio: 16 : 15
[14:33:36] + display dimensions: 759 x 576
[14:33:36] + encoder: H.264 (libx264)
[14:33:36] + preset: medium
[14:33:36] + profile: auto
[14:33:36] + level: auto
[14:33:36] + quality: 22.00 (RF)
[14:33:36] * audio track 1
[14:33:36] + decoder: English (AC3) (2.0 ch) (Dolby Surround) (track 1, id 0x80bd)
[14:33:36] + bitrate: 192 kbps, samplerate: 48000 Hz
[14:33:36] + mixdown: Dolby Surround
[14:33:36] + encoder: AAC (libavcodec)
[14:33:36] + bitrate: 128 kbps, samplerate: 48000 Hz
[14:33:36] * audio track 2
[14:33:36] + decoder: Francais (AC3) (2.0 ch) (Dolby Surround) (track 2, id 0x81bd)
[14:33:36] + bitrate: 192 kbps, samplerate: 48000 Hz
[14:33:36] + mixdown: Dolby Surround
[14:33:36] + encoder: AAC (libavcodec)
[14:33:36] + bitrate: 128 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Couldn't find device name.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
*** libdvdread: CHECK_VALUE failed in src/ifo_read.c:683 ***
*** for vtsi_mat->vtsi_last_sector*2 <= vtsi_mat->vts_last_sector ***
[14:33:36] sync: expecting 62261 video frames
[14:33:36] encx264: encoding at constant RF 22.000000
x264 [info]: using SAR=16/15
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x264 [info]: profile High, level 3.0
[14:33:36] sync: first pts audio 0x81bd is 0
[14:33:36] sync: first pts audio 0x80bd is 2156
[14:33:36] sync: first pts video is 9356
[14:33:36] sync: "Chapter 1" (1) at frame 1 time 9356
Encoding: task 1 of 1, 5.32 % (109.29 fps, avg 122.36 fps, ETA 00h08m01s)[14:34:04] sync: "Chapter 2" (2) at frame 3336 time 12015356
Encoding: task 1 of 1, 7.35 % (80.69 fps, avg 111.63 fps, ETA 00h08m37s)[14:34:17] sync: "Chapter 3" (3) at frame 4579 time 16490156
Encoding: task 1 of 1, 25.19 % (87.99 fps, avg 94.50 fps, ETA 00h08m13s)[14:36:22] sync: "Chapter 4" (4) at frame 15686 time 56475356
Encoding: task 1 of 1, 29.01 % (92.40 fps, avg 95.56 fps, ETA 00h07m43s)[14:36:45] sync: "Chapter 5" (5) at frame 18065 time 65039756
Encoding: task 1 of 1, 36.31 % (90.82 fps, avg 94.62 fps, ETA 00h06m59s)[14:37:36] sync: "Chapter 6" (6) at frame 22614 time 81416156
Encoding: task 1 of 1, 44.74 % (110.62 fps, avg 95.65 fps, ETA 00h06m00s)[14:38:28] sync: "Chapter 7" (7) at frame 27858 time 100294556
Encoding: task 1 of 1, 48.53 % (132.52 fps, avg 96.31 fps, ETA 00h05m32s)[14:38:50] sync: "Chapter 8" (8) at frame 30279 time 109010156
Encoding: task 1 of 1, 59.02 % (115.27 fps, avg 100.07 fps, ETA 00h04m15s)[14:39:44] sync: "Chapter 9" (9) at frame 36762 time 132348956
Encoding: task 1 of 1, 80.69 % (142.99 fps, avg 102.01 fps, ETA 00h01m58s)[14:41:49] sync: "Chapter 10" (10) at frame 50284 time 181028156
Encoding: task 1 of 1, 85.23 % (88.51 fps, avg 102.53 fps, ETA 00h01m29s)[14:42:14] sync: "Chapter 11" (11) at frame 53067 time 191046956
Encoding: task 1 of 1, 98.23 % (101.30 fps, avg 104.65 fps, ETA 00h00m11s)[14:43:21] sync: "Chapter 12" (12) at frame 61169 time 220214156
Encoding: task 1 of 1, 99.89 % (320.23 fps, avg 105.79 fps, ETA 00h00m01s)[14:43:24] reader: done. 1 scr changes
Encoding: task 1 of 1, 99.93 % (320.23 fps, avg 105.79 fps, ETA 00h00m01s)[14:43:25] work: average encoding speed for job is 105.789780 fps
[14:43:25] vfr: lost time: 0 (0 frames)
[14:43:25] vfr: gained time: 0 (0 frames) (0 not accounted for)
[14:43:25] ac3-decoder done: 77826 frames, 0 decoder errors
[14:43:25] ac3-decoder done: 77826 frames, 0 decoder errors
[14:43:25] mpeg2video-decoder done: 62258 frames, 0 decoder errors
[14:43:25] sync: got 62258 frames, 62261 expected
[14:43:25] sync: framerate min 25.000 fps, max 25.000 fps, avg 25.000 fps
x264 [info]: frame I:880 Avg QP:19.51 size: 22920
x264 [info]: frame P:35668 Avg QP:22.99 size: 7571
x264 [info]: frame B:25710 Avg QP:24.56 size: 2381
x264 [info]: consecutive B-frames: 30.1% 40.5% 11.8% 17.6%
x264 [info]: mb I I16..4: 14.3% 78.3% 7.4%
x264 [info]: mb P I16..4: 1.1% 6.2% 0.9% P16..4: 42.1% 12.7% 8.0% 0.0% 0.0% skip:29.0%
x264 [info]: mb B I16..4: 0.0% 0.2% 0.0% B16..8: 39.3% 3.1% 0.7% direct: 1.8% skip:54.9% L0:37.5% L1:57.0% BI: 5.5%
x264 [info]: 8x8 transform intra:76.5% inter:84.2%
x264 [info]: coded y,uvDC,uvAC intra: 62.4% 65.3% 26.0% inter: 19.7% 24.9% 0.7%
x264 [info]: i16 v,h,dc,p: 38% 42% 5% 15%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 20% 14% 31% 4% 6% 8% 5% 7% 6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 13% 61% 9% 2% 3% 4% 2% 3% 2%
x264 [info]: i8c dc,h,v,p: 55% 20% 19% 6%
x264 [info]: Weighted P-Frames: Y:4.1% UV:2.1%
x264 [info]: ref P L0: 64.1% 14.2% 15.4% 6.1% 0.2%
x264 [info]: ref B L0: 87.5% 11.4% 1.1%
x264 [info]: ref B L1: 97.0% 3.0%
x264 [info]: kb/s:1129.01
[14:43:25] mux: track 0, 62258 frames, 351415294 bytes, 1128.83 kbps, fifo 4096
[14:43:25] mux: track 1, 116739 frames, 39904942 bytes, 128.18 kbps, fifo 8192
[14:43:25] mux: track 2, 116739 frames, 39919056 bytes, 128.23 kbps, fifo 8192
Muxing: this may take awhile...[14:43:25] libhb: work result = 0
Encode done!
HandBrake has exited.