[Fixed] CLI Crashes at end of encode.

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

*******************************
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

[Fixed] CLI Crashes at end of encode.

Post by DrXenos »

The CLI is crashing a lot for me in the latest nightly (5893). Previously, it would never never crash (and I would have it transcoding for days).

The last version I had before installing 5893 was 5878.

I checked the Windows event log and I see this (for each crash):

Faulting application name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x527f0233
Faulting module name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x527f0233
Exception code: 0xc0000005
Fault offset: 0x0000000000059f97
Faulting process id: 0x1188
Faulting application start time: 0x01cee284144b9bea
Faulting application path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Faulting module path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Report Id: d0cbe3da-4e77-11e3-8b2b-005056c00008

It doesn't seem to be particular to a given item (I will reattempt and it will work fine).



Here is a typical scan long:

Code: Select all

HandBrake svn5893 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
Ram: 32708 MB, 
GPU Information:
  AMD Radeon HD 7900 Series - 9.12.0.0
Screen: 1920x1200
Temp Dir: C:\Users\Xenos\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Xenos\AppData\Roaming\HandBrake\HandBrake\0.0.0.5893

-------------------------------------------
[23:36:28] CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
[23:36:28]  - Intel microarchitecture Sandy Bridge
[23:36:28]  - logical processor count: 8
[23:36:28] OpenCL device #1: Advanced Micro Devices, Inc. Tahiti
[23:36:28]  - OpenCL version: 1.2 AMD-APP (1084.4)
[23:36:28]  - driver version: 1084.4 (VM)
[23:36:28]  - device type:    GPU
[23:36:28]  - supported:      YES
[23:36:28] Intel Quick Sync Video support: no
[23:36:28] hb_scan: path=D:\Download\Staging\dvd\dredd2012\Dredd, title_index=0
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\Download\Staging\dvd\dredd2012\Dredd/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\Download\Staging\dvd\dredd2012\Dredd/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(D:\Download\Staging\dvd\dredd2012\Dredd) failed (00000000200a41f0)
[23:36:28] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[23:36:28] scan: DVD has 20 title(s)
[23:36:28] scan: scanning title 1
[23:36:28] scan: opening IFO for VTS 1
[23:36:28] scan: duration is 01:35:45 (5745667 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=1, ttn=1, cells=0->25, blocks=0->2468956, 2468957 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: checking audio 2
[23:36:28] scan: id=0x81bd, lang=Espanol (AC3), 3cc=spa ext=0
[23:36:28] scan: checking audio 3
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking audio 4
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking audio 5
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking audio 6
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking audio 7
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking audio 8
[23:36:28] scan: audio channel is not active
[23:36:28] scan: checking subtitle 1
[23:36:28] scan: id=0x20bd, lang=English, 3cc=eng ext=0
[23:36:28] scan: checking subtitle 2
[23:36:28] scan: id=0x21bd, lang=Espanol, 3cc=spa ext=0
[23:36:28] scan: checking subtitle 3
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 4
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 5
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 6
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 7
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 8
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 9
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 10
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 11
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 12
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 13
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 14
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 15
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 16
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 17
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 18
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 19
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 20
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 21
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 22
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 23
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 24
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 25
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 26
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 27
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 28
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 29
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 30
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 31
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: checking subtitle 32
[23:36:28] scan: subtitle channel is not active
[23:36:28] scan: title 1 has 25 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->64371 (64372), 160834 ms
[23:36:28] scan: chap 2 c=1->1, b=64372->146274 (81903), 182033 ms
[23:36:28] scan: chap 3 c=2->2, b=146275->204118 (57844), 150967 ms
[23:36:28] scan: chap 4 c=3->3, b=204119->348445 (144327), 362333 ms
[23:36:28] scan: chap 5 c=4->4, b=348446->440705 (92260), 217000 ms
[23:36:28] scan: chap 6 c=5->5, b=440706->484449 (43744), 107834 ms
[23:36:28] scan: chap 7 c=6->6, b=484450->599824 (115375), 266533 ms
[23:36:28] scan: chap 8 c=7->7, b=599825->684534 (84710), 191600 ms
[23:36:28] scan: chap 9 c=8->8, b=684535->751700 (67166), 162367 ms
[23:36:28] scan: chap 10 c=9->9, b=751701->910868 (159168), 357333 ms
[23:36:28] scan: chap 11 c=10->10, b=910869->1004559 (93691), 227467 ms
[23:36:28] scan: chap 12 c=11->11, b=1004560->1097225 (92666), 212867 ms
[23:36:28] scan: chap 13 c=12->12, b=1097226->1244218 (146993), 304533 ms
[23:36:28] scan: chap 14 c=13->13, b=1244219->1407150 (162932), 372300 ms
[23:36:28] scan: chap 15 c=14->14, b=1407151->1505742 (98592), 229834 ms
[23:36:28] scan: chap 16 c=15->15, b=1505743->1568575 (62833), 147367 ms
[23:36:28] scan: chap 17 c=16->16, b=1568576->1705226 (136651), 333000 ms
[23:36:28] scan: chap 18 c=17->18, b=1705227->1797698 (92472), 194967 ms
[23:36:28] scan: chap 19 c=19->19, b=1797699->1865482 (67784), 135834 ms
[23:36:28] scan: chap 20 c=20->20, b=1865483->1983143 (117661), 226800 ms
[23:36:28] scan: chap 21 c=21->21, b=1983144->2117456 (134313), 261333 ms
[23:36:28] scan: chap 22 c=22->22, b=2117457->2194867 (77411), 159667 ms
[23:36:28] scan: chap 23 c=23->23, b=2194868->2314638 (119771), 250000 ms
[23:36:28] scan: chap 24 c=24->24, b=2314639->2468843 (154205), 529367 ms
[23:36:28] scan: chap 25 c=25->25, b=2468844->2468956 (113), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 2
[23:36:28] scan: opening IFO for VTS 2
[23:36:28] scan: duration is 00:14:27 (867000 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=2, ttn=1, cells=0->1, blocks=0->280459, 280460 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 2 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->280420 (280421), 865600 ms
[23:36:28] scan: chap 2 c=1->1, b=280421->280459 (39), 1400 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 3
[23:36:28] scan: opening IFO for VTS 3
[23:36:28] scan: duration is 00:15:21 (921033 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=3, ttn=1, cells=0->1, blocks=0->297123, 297124 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 3 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->297091 (297092), 920000 ms
[23:36:28] scan: chap 2 c=1->1, b=297092->297123 (32), 1033 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 4
[23:36:28] scan: opening IFO for VTS 4
[23:36:28] scan: duration is 00:01:53 (113333 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=4, ttn=1, cells=0->1, blocks=0->36518, 36519 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 4 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->36483 (36484), 112100 ms
[23:36:28] scan: chap 2 c=1->1, b=36484->36518 (35), 1233 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 5
[23:36:28] scan: opening IFO for VTS 5
[23:36:28] scan: duration is 00:02:31 (151834 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=5, ttn=1, cells=0->1, blocks=0->48952, 48953 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 5 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->48918 (48919), 150700 ms
[23:36:28] scan: chap 2 c=1->1, b=48919->48952 (34), 1133 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 6
[23:36:28] scan: opening IFO for VTS 6
[23:36:28] scan: duration is 00:02:00 (120033 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=6, ttn=1, cells=0->1, blocks=0->38843, 38844 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 6 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->38802 (38803), 118533 ms
[23:36:28] scan: chap 2 c=1->1, b=38803->38843 (41), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 7
[23:36:28] scan: opening IFO for VTS 7
[23:36:28] scan: duration is 00:02:33 (153433 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=7, ttn=1, cells=0->1, blocks=0->49560, 49561 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 7 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->49524 (49525), 152166 ms
[23:36:28] scan: chap 2 c=1->1, b=49525->49560 (36), 1266 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 8
[23:36:28] scan: opening IFO for VTS 8
[23:36:28] scan: duration is 00:02:57 (177000 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=8, ttn=1, cells=0->1, blocks=0->57161, 57162 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 8 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->57060 (57061), 175734 ms
[23:36:28] scan: chap 2 c=1->1, b=57061->57161 (101), 1266 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 9
[23:36:28] scan: opening IFO for VTS 9
[23:36:28] scan: duration is 00:02:30 (150500 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=9, ttn=1, cells=0->1, blocks=0->48828, 48829 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 9 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->48787 (48788), 149000 ms
[23:36:28] scan: chap 2 c=1->1, b=48788->48828 (41), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 10
[23:36:28] scan: opening IFO for VTS 10
[23:36:28] scan: duration is 00:01:35 (95567 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=10, ttn=1, cells=0->1, blocks=0->30925, 30926 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 10 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->30893 (30894), 94533 ms
[23:36:28] scan: chap 2 c=1->1, b=30894->30925 (32), 1033 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 11
[23:36:28] scan: opening IFO for VTS 11
[23:36:28] scan: duration is 00:01:35 (95567 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=11, ttn=1, cells=0->1, blocks=0->30925, 30926 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 11 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->30893 (30894), 94533 ms
[23:36:28] scan: chap 2 c=1->1, b=30894->30925 (32), 1033 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 12
[23:36:28] scan: opening IFO for VTS 12
[23:36:28] scan: duration is 00:02:34 (154066 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=12, ttn=1, cells=0->1, blocks=0->49907, 49908 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 12 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->49875 (49876), 153066 ms
[23:36:28] scan: chap 2 c=1->1, b=49876->49907 (32), 1000 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 13
[23:36:28] scan: opening IFO for VTS 13
[23:36:28] scan: duration is 00:01:10 (70333 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADC10
[23:36:28] scan: vts=13, ttn=1, cells=0->1, blocks=0->22838, 22839 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 13 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->22797 (22798), 68834 ms
[23:36:28] scan: chap 2 c=1->1, b=22798->22838 (41), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 14
[23:36:28] scan: opening IFO for VTS 14
[23:36:28] scan: duration is 00:01:29 (89333 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADE50
[23:36:28] scan: vts=14, ttn=1, cells=0->1, blocks=0->28988, 28989 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 14 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->28948 (28949), 87834 ms
[23:36:28] scan: chap 2 c=1->1, b=28949->28988 (40), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 15
[23:36:28] scan: opening IFO for VTS 15
[23:36:28] scan: duration is 00:01:10 (70500 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADE50
[23:36:28] scan: vts=15, ttn=1, cells=0->1, blocks=0->22893, 22894 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 15 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->22852 (22853), 69000 ms
[23:36:28] scan: chap 2 c=1->1, b=22853->22893 (41), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 16
[23:36:28] scan: opening IFO for VTS 16
[23:36:28] scan: duration is 00:01:02 (62066 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADF70
[23:36:28] scan: vts=16, ttn=1, cells=0->1, blocks=0->20097, 20098 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 16 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->20066 (20067), 61066 ms
[23:36:28] scan: chap 2 c=1->1, b=20067->20097 (31), 1000 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 17
[23:36:28] scan: opening IFO for VTS 17
[23:36:28] scan: duration is 00:01:23 (83166 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADF70
[23:36:28] scan: vts=17, ttn=1, cells=0->1, blocks=0->26963, 26964 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 17 has 2 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->26923 (26924), 81667 ms
[23:36:28] scan: chap 2 c=1->1, b=26924->26963 (40), 1500 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 18
[23:36:28] scan: opening IFO for VTS 18
[23:36:28] scan: duration is 00:00:13 (13000 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADF70
[23:36:28] scan: vts=18, ttn=1, cells=0->0, blocks=0->1449, 1450 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 18 has 1 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->1449 (1450), 13000 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 19
[23:36:28] scan: opening IFO for VTS 19
[23:36:28] scan: duration is 00:00:23 (23000 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADF70
[23:36:28] scan: vts=19, ttn=1, cells=0->0, blocks=0->9568, 9569 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 19 has 1 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->9568 (9569), 23000 ms
[23:36:28] scan: aspect = 1.77778
[23:36:28] scan: scanning title 20
[23:36:28] scan: opening IFO for VTS 20
[23:36:28] scan: duration is 00:00:23 (23000 ms)
[23:36:28] pgc_id: 1, pgn: 1: pgc: 00000000200ADF70
[23:36:28] scan: vts=20, ttn=1, cells=0->0, blocks=0->9568, 9569 blocks
[23:36:28] scan: checking audio 1
[23:36:28] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[23:36:28] scan: title 20 has 1 chapters
[23:36:28] scan: chap 1 c=0->0, b=0->9568 (9569), 23000 ms
[23:36:28] scan: aspect = 1.77778
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[23:36:29] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=448000 Espanol (AC3) (5.1 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 62/58/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 2
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:29] Title is likely interlaced or telecined (5 out of 10 previews). You should do something about that.
[23:36:29] scan: decoding previews for title 3
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 4
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 60/62/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 5
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 60/62/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 6
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 58/60/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 7
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:29] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:29] scan: 10 previews, 720x480, 23.976 fps, autocrop = 60/62/0/0, aspect 16:9, PAR 32:27
[23:36:29] scan: decoding previews for title 8
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:29] scan: title angle(s) 1
[23:36:30] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:30] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:30] scan: decoding previews for title 9
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:30] scan: title angle(s) 1
[23:36:30] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:30] scan: 10 previews, 720x480, 23.976 fps, autocrop = 62/58/0/0, aspect 16:9, PAR 32:27
[23:36:30] scan: decoding previews for title 10
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:30] scan: title angle(s) 1
[23:36:30] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:30] scan: 10 previews, 720x480, 23.976 fps, autocrop = 66/62/0/0, aspect 16:9, PAR 32:27
[23:36:30] scan: decoding previews for title 11
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:30] scan: title angle(s) 1
[23:36:30] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:30] scan: 10 previews, 720x480, 23.976 fps, autocrop = 66/62/0/0, aspect 16:9, PAR 32:27
[23:36:30] scan: decoding previews for title 12
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:30] scan: title angle(s) 1
[23:36:30] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:31] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:31] scan: decoding previews for title 13
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:31] scan: title angle(s) 1
[23:36:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:31] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:31] scan: decoding previews for title 14
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:31] scan: title angle(s) 1
[23:36:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:31] scan: 10 previews, 720x480, 23.976 fps, autocrop = 58/54/0/0, aspect 16:9, PAR 32:27
[23:36:31] scan: decoding previews for title 15
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:31] scan: title angle(s) 1
[23:36:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:31] scan: 10 previews, 720x480, 23.976 fps, autocrop = 62/58/0/0, aspect 16:9, PAR 32:27
[23:36:31] scan: decoding previews for title 16
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:31] scan: title angle(s) 1
[23:36:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:32] scan: decoding previews for title 17
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:32] scan: title angle(s) 1
[23:36:32] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[23:36:32] scan: decoding previews for title 18
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:32] scan: title angle(s) 1
[23:36:32] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[23:36:32] scan: content PAR gives wrong aspect 2.37; using container aspect 1.78
[23:36:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 56/88/168/168, aspect 16:9, PAR 853:720
[23:36:32] scan: decoding previews for title 19
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:32] scan: title angle(s) 1
[23:36:32] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[23:36:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 58/54/0/0, aspect 16:9, PAR 32:27
[23:36:32] scan: decoding previews for title 20
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[23:36:32] scan: title angle(s) 1
[23:36:32] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[23:36:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 58/54/0/0, aspect 16:9, PAR 32:27
[23:36:32] libhb: scan thread found 20 valid title(s)
Please let me know what else would be helpful in tracking this bug.
Deleted User 11865

Re: CLI Crashes

Post by Deleted User 11865 »

The encode log?
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

Yeah, I thought about that after I had shut it down, and reinstalled the older version (I had a lot of jobs I wanted to encode overnight). What I will do is wait for the next nightly, and if that crashes, I will be sure to save the encode log.

Thanks for the reply.
scooter
Posts: 2
Joined: Fri Oct 25, 2013 12:56 pm

Re: CLI Crashes

Post by scooter »

I'm seeing a similar CLI crash trying to encode today with svn5893.

Event Viewer:

Code: Select all

Faulting application name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x527f0233
Faulting module name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x527f0233
Exception code: 0xc0000005
Fault offset: 0x00000000009c57dc
Faulting process id: 0xd10
Faulting application start time: 0x01cee44f11e575a5
Faulting application path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Faulting module path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Report Id: d3d2a666-5043-11e3-853a-c8600014a2c8
Encode log:

Code: Select all

HandBrake svn5893 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16324 MB, 
GPU Information:
  NVIDIA GeForce GTX 670 - 9.18.13.3165
Screen: 2560x1440
Temp Dir: C:\Users\Adrian\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Adrian\AppData\Roaming\HandBrake\HandBrake\0.0.0.5893

-------------------------------------------

CLI Query:  -i "\\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts" -t 1 --angle 1 -c 1 -o "\\DISKSTATION\humax\Black Swan [2010].1080i.TV.x264.AC3.mkv"  -f mkv  --decomb -w 1920 --crop 140:140:0:0 --loose-anamorphic  --modulus 2 -e x264 -q 20 --vfr -a 1,1 -E copy:ac3,av_aac -6 auto,dpl2 -R Auto,Auto -B 0,160 -D 0,0 --gain 0,0 --audio-fallback ac3 --markers="C:\Users\Adrian\AppData\Local\Temp\Black Swan [2010].1080i.TV.x264.AC3-1-chapters.csv" --h264-level="4.1"  --h264-profile=high  --verbose=2
User Query: False

[11:12:29] hb_init: starting libhb thread
[11:12:29] thread 1ffe200 started ("libhb")
HandBrake svn5893 (2013111001) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening \\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts...
[11:12:29] CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
[11:12:29]  - Intel microarchitecture Sandy Bridge
[11:12:29]  - logical processor count: 12
[11:12:29] OpenCL device #1: NVIDIA Corporation GeForce GTX 670
[11:12:29]  - OpenCL version: 1.1 CUDA
[11:12:29]  - driver version: 331.65
[11:12:29]  - device type:    GPU
[11:12:29]  - supported:      no
[11:12:29] Intel Quick Sync Video support: no
[11:12:29] hb_scan: path=\\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts, title_index=1
[11:12:29] thread 1fff0c0 started ("scan")
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening \\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening \\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(\\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts) failed (0000000000357fd0)
[11:12:29] 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
[11:12:29] dvd: not a dvd - trying as a stream/file instead
[11:12:29] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[11:12:29] Found the following PIDS
[11:12:29]     Video PIDS : 
[11:12:29]       0x12d type H.264 (0x1b) (PCR)
[11:12:29]     Audio PIDS : 
[11:12:29]       0x12e type LATM AAC (0x11)
[11:12:29]     Subtitle PIDS : 
[11:12:29]     Other PIDS : 
[11:12:29]       0x131 type DVB Subtitling (0x0)
[11:12:29] stream id 0x12e (type 0x11 substream 0x0) audio 0x12e
[11:12:29] scan: decoding previews for title 1
[11:12:29] scan: preview 1
[aac_latm @ 00000000003becc0] audio config changed
[11:12:29] fifo_close: trashing 0 buffer(s)
[11:12:29] scan: audio 0x12e: aac_latm, rate=48000Hz, bitrate=200000 English (LATM AAC) (5.1 ch)
Scanning title 1 of 1, preview 1, 10.00 %[11:12:29] scan: preview 2
[11:12:29] scan: preview 3
[11:12:29] scan: preview 4
[11:12:30] scan: preview 5
Scanning title 1 of 1, preview 5, 50.00 %[11:12:30] scan: preview 6
[11:12:30] scan: preview 7
Scanning title 1 of 1, preview 7, 70.00 %[11:12:30] scan: preview 8
[11:12:30] scan: preview 9
[11:12:30] scan: preview 10
Scanning title 1 of 1, preview 10, 100.00 %[11:12:30] scan: 10 previews, 1920x1080, 25.000 fps, autocrop = 56/140/0/0, aspect 16:9, PAR 1:1
[11:12:30] Freed 1 buffers of size 1024
[11:12:30] Freed 1 buffers of size 2048
[11:12:30] Freed 1 buffers of size 65536
[11:12:30] Freed 1 buffers of size 131072
[11:12:30] Freed 1 buffers of size 262144
[11:12:30] Freed 1 buffers of size 4194304
[11:12:30] Allocated 4923392 bytes of buffers on this pass and Freed 4656128 bytes, 267264 bytes leaked
[11:12:30] stream: 13 good frames, 0 errors (0%)
[11:12:30] thread 1fff0c0 exited ("scan")
[11:12:30] thread 1fff0c0 joined ("scan")
[11:12:30] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: \\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts
  + duration: 01:40:25
  + size: 1920x1080, pixel aspect: 1/1, display aspect: 1.78, 25.000 fps
  + autocrop: 56/140/0/0
  + support opencl: no
  + support hwd: yes
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 01:40:25
  + audio tracks:
    + 1, English (LATM AAC) (5.1 ch) (iso639-2: eng)
  + subtitle tracks:
Reading chapter markers from file C:\Users\Adrian\AppData\Local\Temp\Black Swan [2010].1080i.TV.x264.AC3-1-chapters.csv
AC3 Passthru requested and input codec is not compatible for track 1, using AC3 encoder
[11:12:30] thread 1fff0c0 started ("work")
[11:12:30] 1 job(s) to process
[11:12:30] starting job
[11:12:30] thread 5176ed0 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 0
[11:12:30] thread 5177630 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 1
[11:12:30] thread 5177ae0 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 2
[11:12:30] thread 5177c80 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 3
[11:12:30] thread 5177e20 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 4
[11:12:30] thread 5177fc0 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 5
[11:12:30] thread 5178160 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 6
[11:12:30] thread 5178300 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 7
[11:12:30] thread 51784a0 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 8
[11:12:30] thread 5178640 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 9
[11:12:30] thread 51787e0 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 10
[11:12:30] thread 5178980 started ("yadif_filter_segment")
[11:12:30] yadif thread started for segment 11
[11:12:30] thread 5178b20 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 0
[11:12:30] thread 5178cc0 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 1
[11:12:30] thread 5178e60 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 2
[11:12:30] thread 5179000 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 3
[11:12:30] thread 51791a0 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 4
[11:12:30] thread 5179340 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 5
[11:12:30] thread 51794e0 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 6
[11:12:30] thread 5179680 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 7
[11:12:30] thread 5179820 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 8
[11:12:30] thread 3700b0 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 9
[11:12:30] thread 370250 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 10
[11:12:30] thread 3703f0 started ("decomb_filter_segment")
[11:12:30] decomb filter thread started for segment 11
[11:12:30] thread 370590 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 0
[11:12:30] thread 370730 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 1
[11:12:30] thread 3708d0 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 2
[11:12:30] thread 370a70 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 3
[11:12:30] thread 370c10 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 4
[11:12:30] thread 370db0 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 5
[11:12:30] thread 370f50 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 6
[11:12:30] thread 3710f0 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 7
[11:12:30] thread 371290 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 8
[11:12:30] thread 371430 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 9
[11:12:30] thread 3715d0 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 10
[11:12:30] thread 371770 started ("decomb_check_segment")
[11:12:30] decomb check thread started for segment 11
[11:12:30] thread 371910 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 0
[11:12:30] thread 371ab0 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 1
[11:12:30] thread 371c50 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 2
[11:12:30] thread 371df0 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 3
[11:12:30] thread 3758b0 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 4
[11:12:30] thread 375a50 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 5
[11:12:30] thread 375bf0 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 6
[11:12:30] thread 375d90 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 7
[11:12:30] thread 375f30 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 8
[11:12:30] thread 3760d0 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 9
[11:12:30] thread 376270 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 10
[11:12:30] thread 376410 started ("mask_filter_segment")
[11:12:30] mask filter thread started for segment 11
[11:12:30] thread 3765b0 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 0
[11:12:30] thread 376750 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 1
[11:12:30] thread 3768f0 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 2
[11:12:30] thread 376a90 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 3
[11:12:30] thread 376c30 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 4
[11:12:30] thread 376dd0 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 5
[11:12:30] thread 376f70 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 6
[11:12:30] thread 377110 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 7
[11:12:30] thread 3772b0 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 8
[11:12:30] thread 377450 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 9
[11:12:30] thread 3775f0 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 10
[11:12:30] thread 513f040 started ("mask_erode_segment")
[11:12:30] mask erode thread started for segment 11
[11:12:30] thread 513f1e0 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 0
[11:12:30] thread 513f380 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 1
[11:12:30] thread 513f520 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 2
[11:12:30] thread 513f6c0 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 3
[11:12:30] thread 513f860 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 4
[11:12:30] thread 513fa00 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 5
[11:12:30] thread 513fba0 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 6
[11:12:30] thread 513fd40 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 7
[11:12:30] thread 513fee0 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 8
[11:12:30] thread 5140080 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 9
[11:12:30] thread 5140220 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 10
[11:12:30] thread 51403c0 started ("mask_dilate_segment")
[11:12:30] mask dilate thread started for segment 11
[11:12:30] sync: expecting 150649 video frames
[11:12:30] work: only 1 chapter, disabling chapter markers
[11:12:30] job configuration:
[11:12:30]  * source
[11:12:30]    + \\DISKSTATION\humax\Black Swan_20131116_2102 (02).ts
[11:12:30]    + title 1, chapter(s) 1 to 1
[11:12:30]  * destination
[11:12:30]    + \\DISKSTATION\humax\Black Swan [2010].1080i.TV.x264.AC3.mkv
[11:12:30]    + container: Matroska (avformat)
[11:12:30]  * video track
[11:12:30]    + decoder: h264
[11:12:30]      + bitrate 200 kbps
[11:12:30]    + filters
[11:12:30]      + Decomb (default settings)
[11:12:30]      + Framerate Shaper (0:27000000:1080000)
[11:12:30]        + frame rate: same as source (around 25.000 fps)
[11:12:30]      + Crop and Scale (1920:800:140:140:0:0)
[11:12:30]        + source: 1920 * 1080, crop (140/140/0/0): 1920 * 800, scale: 1920 * 800
[11:12:30]    + loose anamorphic
[11:12:30]      + storage dimensions: 1920 * 800, mod 2
[11:12:30]      + pixel aspect ratio: 1 / 1
[11:12:30]      + display dimensions: 1920 * 800
[11:12:30]    + encoder: H.264 (x264)
[11:12:30]      + h264 profile: high
[11:12:30]      + h264 level: 4.1
[11:12:30]      + quality: 20.00 (RF)
[11:12:30]  * audio track 1
[11:12:30]    + decoder: English (LATM AAC) (5.1 ch) (track 1, id 0x12e)
[11:12:30]      + bitrate: 200 kbps, samplerate: 48000 Hz
[11:12:30]    + mixdown: 5.1 Channels
[11:12:30]    + encoder: AC3
[11:12:30]      + bitrate: 640 kbps, samplerate: 48000 Hz
[11:12:30]  * audio track 2
[11:12:30]    + decoder: English (LATM AAC) (5.1 ch) (track 1, id 0x12e)
[11:12:30]      + bitrate: 200 kbps, samplerate: 48000 Hz
[11:12:30]    + mixdown: Dolby Pro Logic II
[11:12:30]    + encoder: AAC (avcodec)
[11:12:30]      + bitrate: 160 kbps, samplerate: 48000 Hz
[11:12:30] file is MPEG Transport Stream with 188 byte packets offset 0 bytes
[11:12:30] thread 5140560 started ("Reader")
[11:12:30] thread 5140700 started ("Decomb")
[11:12:30] reader: first SCR 914 id 0x12d DTS 1
[11:12:30] thread 51408a0 started ("Framerate Shaper")
[11:12:30] thread 5140a40 started ("Crop and Scale")
[11:12:30] thread 5140be0 started ("AudioSynchronization")
[11:12:30] thread 5140d80 started ("AudioSynchronization")
[11:12:30] thread 5153860 started ("Video decoder (libavcodec)")
[11:12:30] encx264: encoding at constant RF 20.000000
[11:12:30] encx264: unparsed options: level=4.1:vbv-bufsize=78125:vbv-maxrate=62500
[11:12:30] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x264 [info]: profile High, level 4.1
[11:12:30] thread 6d7ade0 started ("H.264/AVC encoder (libx264)")
[11:12:30] thread 6d7b120 started ("Audio decoder (libavcodec)")
[aac_latm @ 000000000500ff20] audio config changed
[11:12:30] thread 6d7b2c0 started ("AVCodec Audio encoder (libavcodec)")
[11:12:30] thread 6d7b460 started ("Audio decoder (libavcodec)")
[aac_latm @ 0000000005010740] audio config changed
[11:12:30] thread 6d7b600 started ("AVCodec Audio encoder (libavcodec)")
[11:12:30] thread 6d7b7a0 started ("Video Synchronization")
[11:12:30] h264: "Chapter 1" (1) at frame 0 time 17999
[11:12:30] sync: first pts is 17999
[11:12:30] Writing Metadata to output file...
[11:12:30] thread 6d7af80 started ("Muxer")
[11:12:30] thread 6d7b940 started ("Muxer")
[11:12:31] 4.279989s: Video -> Film
[11:12:47] 36.559990s: Film -> Video
[11:13:12] 111.719986s: Video -> Film
[aac_latm @ 0000000005010740] audio config changed
[aac_latm @ 000000000500ff20] audio config changed
[aac_latm @ 0000000005010740] audio config changed
[aac_latm @ 000000000500ff20] audio config changed
Not sure whether the crash is related to the last few "audio config changed" messages in the log. This is happening because the recording was made from a channel with ads and the 5.1 audio changes to 2.0 going into the ad break but some of the 2.0 audio has to be retained to ensure a clean cut when the ads are edited out. I've made many encodes like this before without problems, however.

Adrian
drizzle
Posts: 9
Joined: Fri Nov 22, 2013 5:39 pm

Re: CLI Crashes

Post by drizzle »

builds after 5879 crash intermittently, seems to be always at the end when the muxing is complete or nearly complete
Perhaps the 5890 muxer change is somehow related?

crash for build 5901, event viewer

Faulting application name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x528c3130
Faulting module name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x528c3130
Exception code: 0xc0000005
Fault offset: 0x0000000000059fb7
Faulting process id: 0x4f8
Faulting application start time: 0x01cee758cfeb4308
Faulting application path: D:\hb\HandBrakeCLI.exe
Faulting module path: D:\hb\HandBrakeCLI.exe

log here, if all went well the next expected message would have been
[timestamp] libhb: work result = 0

Code: Select all

D:\hb>handbrakeCLI.exe -q 23 -o "c:\video\venato-file2.mp4" -i "d:\vout\venato-file2.mp4" --ab 80 -E fdk_aac 
-U --optimize -e x264 --markers --strict-anamorphic -x b-adapt=2:trellis=2:direct=auto:rc-lookahead=50
[23:59:47] hb_init: starting libhb thread
HandBrake svn5901 (2013112001) - MinGW x86_64 - http://handbrake.fr
8 CPUs detected
Opening d:\vout\venato-file2.mp4...
[23:59:47] CPU:
[23:59:47]  - logical processor count: 8
[23:59:47] OpenCL device #1: Advanced Micro Devices, Inc. Caicos
[23:59:47]  - OpenCL version: 1.2 AMD-APP (1268.1)
[23:59:47]  - driver version: 1268.1 (VM)
[23:59:47]  - device type:    GPU
[23:59:47]  - supported:      YES
[23:59:47] Intel Quick Sync Video support: no
[23:59:47] hb_scan: path=d:\vout\venato-file2.mp4, title_index=1
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening d:\vout\venato-file2.mp4/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening d:\vout\venato-file2.mp4/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(d:\vout\venato-file2.mp4) failed (0000000001bd1f20)
[23:59:47] 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
[23:59:47] dvd: not a dvd - trying as a stream/file instead
Input #0, mov,mp4,m4a,3gp,3g2,mj2, from 'd:\vout\venato-file2.mp4':
  Metadata:
    major_brand     : mp42
    minor_version   : 1
    compatible_brands: mp42mp41
    creation_time   : 2013-11-19 19:54:04
  Duration: 00:16:00.47, start: 0.000000, bitrate: 7253 kb/s
    Stream #0.0(eng): Video: h264 (Constrained Baseline), yuv420p, 1280x720, 6881 kb/s, 23.98 fps, 23.98 tbr, 24k tbn (default)
    Metadata:
      creation_time   : 2013-11-19 19:54:04
    Stream #0.1(eng): Audio: aac, 44100 Hz, stereo, fltp, 100 kb/s (default)
    Metadata:
      creation_time   : 2013-11-19 19:54:04
    Stream #0.2(eng): Data: rtp  / 0x20707472, 8 kb/s
    Metadata:
      creation_time   : 2013-11-19 19:54:04
    Stream #0.3(eng): Data: rtp  / 0x20707472, 250 kb/s
    Metadata:
      creation_time   : 2013-11-19 19:54:04
[23:59:47] dxva2:IDirect3D9_CreateDevice failed
[23:59:47] dxva2:Failed to create Direct3D device
[23:59:47] scan: decoding previews for title 1
[23:59:47] scan: audio 0x1: aac, rate=44100Hz, bitrate=100255 English (aac) (2.0 ch)
Scanning title 1 of 1, preview 3, 30.00 %[23:59:47] scan: 10 previews, 1280x720, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
Scanning title 1 of 1, preview 10, 100.00 %[23:59:47] libhb: scan thread found 1 valid title(s)
+ title 1:
  + stream: d:\vout\venato-file2.mp4
  + duration: 00:16:00
  + size: 1280x720, pixel aspect: 1/1, display aspect: 1.78, 23.976 fps
  + autocrop: 0/0/0/0
  + support opencl: yes
  + support hwd: no
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:16:00
  + audio tracks:
    + 1, English (aac) (2.0 ch) (iso639-2: eng)
  + subtitle tracks:
[23:59:48] 1 job(s) to process
[23:59:48] starting job
[23:59:48] work: mixdown not specified, track 1 setting mixdown Stereo
[23:59:48] sync: expecting 23028 video frames
[23:59:48] work: only 1 chapter, disabling chapter markers
[23:59:48] job configuration:
[23:59:48]  * source
[23:59:48]    + d:\vout\venato-file2.mp4
[23:59:48]    + title 1, chapter(s) 1 to 1
[23:59:48]    + container: mov,mp4,m4a,3gp,3g2,mj2
[23:59:48]    + data rate: 7268 kbps
[23:59:48]  * destination
[23:59:48]    + c:\video\venato-file2.mp4
[23:59:48]    + container: MPEG-4 (avformat)
[23:59:48]      + optimized for HTTP streaming (fast start)
[23:59:48]  * video track
[23:59:48]    + decoder: h264
[23:59:48]      + bitrate 6881 kbps
[23:59:48]    + filters
[23:59:48]      + Framerate Shaper (0:27000000:1126125)
[23:59:48]        + frame rate: same as source (around 23.976 fps)
[23:59:48]      + Crop and Scale (1280:720:0:0:0:0)
[23:59:48]        + source: 1280 * 720, crop (0/0/0/0): 1280 * 720, scale: 1280 * 720
[23:59:48]    + strict anamorphic
[23:59:48]      + storage dimensions: 1280 * 720, mod 0
[23:59:48]      + pixel aspect ratio: 1 / 1
[23:59:48]      + display dimensions: 1280 * 720
[23:59:48]    + encoder: H.264 (x264)
[23:59:48]      + options: b-adapt=2:trellis=2:direct=auto:rc-lookahead=50
[23:59:48]      + quality: 23.00 (RF)
[23:59:48]  * audio track 1
[23:59:48]    + decoder: English (aac) (2.0 ch) (track 1, id 0x1)
[23:59:48]      + bitrate: 100 kbps, samplerate: 44100 Hz
[23:59:48]    + mixdown: Stereo
[23:59:48]    + dither: triangular
[23:59:48]    + encoder: AAC (FDK)
[23:59:48]      + bitrate: 80 kbps, samplerate: 44100 Hz
[23:59:48] reader: first SCR 0 id 0x0 DTS 0
[23:59:48] dxva2:IDirect3D9_CreateDevice failed
[23:59:48] dxva2:Failed to create Direct3D device
[23:59:48] encx264: min-keyint: 24, keyint: 240
[23:59:48] encx264: encoding at constant RF 23.000000
[23:59:48] encx264: unparsed options: b-adapt=2:trellis=2:direct=auto:rc-lookahead=50
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX XOP FMA4 FMA3 LZCNT BMI1
x264 [info]: profile High, level 3.1
Encoding: task 1 of 1, 99.62 % (70.28 fps, avg 60.84 fps, ETA 00h00m02s)
[00:06:04] reader: done. 1 scr changes
Encoding: task 1 of 1, 100.00 % (70.28 fps, avg 60.84 fps, ETA 00h00m02s)
[00:06:05] work: average encoding speed for job is 60.840439 fps
Encoding: task 1 of 1, 100.00 % (70.28 fps, avg 60.84 fps, ETA 00h00m02s)
[00:06:05] sync: got 23028 frames, 23028 expected
[00:06:05] render: lost time: 0 (0 frames)
[00:06:05] render: gained time: 0 (0 frames) (0 not accounted for)
[00:06:05] h264-decoder done: 23028 frames, 0 decoder errors, 0 drops
x264 [info]: frame I:110   Avg QP:18.47  size: 61996
x264 [info]: frame P:8412  Avg QP:21.18  size: 10447
x264 [info]: frame B:14506 Avg QP:24.67  size:  3022
x264 [info]: consecutive B-frames: 13.2%  0.8% 23.0% 63.0%
x264 [info]: mb I  I16..4: 11.8% 63.8% 24.5%
x264 [info]: mb P  I16..4:  2.3%  4.1%  0.5%  P16..4: 41.4% 10.1%  6.3%  0.0%  0.0%    skip:35.4%
x264 [info]: mb B  I16..4:  0.4%  0.5%  0.0%  B16..8: 43.4%  2.1%  0.2%  direct: 1.0%  skip:52.3%  L0:46.4% L1:52.0% BI: 1.6%
x264 [info]: 8x8 transform intra:58.9% inter:73.3%
x264 [info]: direct mvs  spatial:100.0% temporal:0.0%
x264 [info]: coded y,uvDC,uvAC intra: 54.7% 58.1% 7.8% inter: 7.1% 15.9% 0.1%
x264 [info]: i16 v,h,dc,p: 18% 24% 31% 27%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 17% 18% 27%  5%  7%  7%  7%  6%  7%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 21% 20% 21%  5%  8%  7%  7%  5%  4%
x264 [info]: i8c dc,h,v,p: 59% 20% 18%  4%
x264 [info]: Weighted P-Frames: Y:1.0% UV:0.6%
x264 [info]: ref P L0: 65.8% 12.6% 14.6%  7.0%  0.0%
x264 [info]: ref B L0: 92.0%  6.1%  1.9%
x264 [info]: ref B L1: 94.7%  5.3%
x264 [info]: kb/s:1153.94
[00:06:05] aac-decoder done: 0 frames, 0 decoder errors, 0 drops
Muxing: this may take awhile...[00:06:05] mux: track 0, 23028 frames, 138534555 bytes, 1153.95 kbps, fifo 4096
[00:06:05] mux: track 1, 41364 frames, 9605319 bytes, 80.01 kbps, fifo 8192
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

Yes, it seems to be the same as mine. Same exception number (access violation, which indicates a bad pointer, array index, or stack overrun, etc.), and same offset. If one of the developers could attach a debugger to the running process, the offset would tell you the exact point of the crash.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

OK, I finally found time to play it the latest nightly (5912), and I still get a crash in HandBrakeCLI. I made a large queue and encode with 5878, and it went fine. I then attempted to encode an equivalent queue with 5912 and about an hour in, it crashed (although I think the amount of time may be random).

The relevant logs are below. Thanks for your time.


Scan Log:

Code: Select all

HandBrake svn5912 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
Ram: 32708 MB, 
GPU Information:
  AMD Radeon HD 7900 Series - 9.12.0.0
Screen: 1920x1200
Temp Dir: C:\Users\Xenos\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Xenos\AppData\Roaming\HandBrake\HandBrake\0.0.0.5912

-------------------------------------------
[09:42:36] CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
[09:42:36]  - Intel microarchitecture Sandy Bridge
[09:42:36]  - logical processor count: 8
[09:42:36] OpenCL device #1: Advanced Micro Devices, Inc. Tahiti
[09:42:36]  - OpenCL version: 1.2 AMD-APP (1084.4)
[09:42:36]  - driver version: 1084.4 (VM)
[09:42:36]  - device type:    GPU
[09:42:36]  - supported:      YES
[09:42:36] Intel Quick Sync Video support: no
[09:42:36] hb_scan: path=D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1, title_index=0
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1) failed (000000001f45b600)
[09:42:36] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[09:42:36] scan: DVD has 13 title(s)
[09:42:36] scan: scanning title 1
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 03:31:55 (12715834 ms)
[09:42:36] pgc_id: 1, pgn: 1: pgc: 000000001F3F14F0
[09:42:36] scan: vts=1, ttn=1, cells=0->42, blocks=0->3686726, 3686727 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 1 has 39 chapters
[09:42:36] scan: chap 1 c=0->0, b=0->43080 (43081), 115166 ms
[09:42:36] scan: chap 2 c=1->1, b=43081->196771 (153691), 521600 ms
[09:42:36] scan: chap 3 c=2->2, b=196772->328548 (131777), 458333 ms
[09:42:36] scan: chap 4 c=3->3, b=328549->452102 (123554), 442000 ms
[09:42:36] scan: chap 5 c=4->4, b=452103->620884 (168782), 577667 ms
[09:42:36] scan: chap 6 c=5->5, b=620885->735754 (114870), 409000 ms
[09:42:36] scan: chap 7 c=6->7, b=735755->739343 (3589), 29734 ms
[09:42:36] scan: chap 8 c=8->8, b=739344->767719 (28376), 89033 ms
[09:42:36] scan: chap 9 c=9->9, b=767720->889497 (121778), 422667 ms
[09:42:36] scan: chap 10 c=10->10, b=889498->1081676 (192179), 655000 ms
[09:42:36] scan: chap 11 c=11->11, b=1081677->1202336 (120660), 432333 ms
[09:42:36] scan: chap 12 c=12->12, b=1202337->1283626 (81290), 281000 ms
[09:42:36] scan: chap 13 c=13->13, b=1283627->1373812 (90186), 315333 ms
[09:42:36] scan: chap 14 c=14->14, b=1373813->1473895 (100083), 317000 ms
[09:42:36] scan: chap 15 c=15->16, b=1473896->1477764 (3869), 29633 ms
[09:42:36] scan: chap 16 c=17->17, b=1477765->1521139 (43375), 146333 ms
[09:42:36] scan: chap 17 c=18->18, b=1521140->1613923 (92784), 315767 ms
[09:42:36] scan: chap 18 c=19->19, b=1613924->1774404 (160481), 581000 ms
[09:42:36] scan: chap 19 c=20->20, b=1774405->1896417 (122013), 390000 ms
[09:42:36] scan: chap 20 c=21->21, b=1896418->2046774 (150357), 543333 ms
[09:42:36] scan: chap 21 c=22->22, b=2046775->2114934 (68160), 249000 ms
[09:42:36] scan: chap 22 c=23->23, b=2114935->2210524 (95590), 289333 ms
[09:42:36] scan: chap 23 c=24->25, b=2210525->2214158 (3634), 28734 ms
[09:42:36] scan: chap 24 c=26->26, b=2214159->2271543 (57385), 184567 ms
[09:42:36] scan: chap 25 c=27->27, b=2271544->2411834 (140291), 482867 ms
[09:42:36] scan: chap 26 c=28->28, b=2411835->2582140 (170306), 613000 ms
[09:42:36] scan: chap 27 c=29->29, b=2582141->2704173 (122033), 422000 ms
[09:42:36] scan: chap 28 c=30->30, b=2704174->2875062 (170889), 560667 ms
[09:42:36] scan: chap 29 c=31->31, b=2875063->2949520 (74458), 255667 ms
[09:42:36] scan: chap 30 c=32->33, b=2949521->2953153 (3633), 29567 ms
[09:42:36] scan: chap 31 c=34->34, b=2953154->3021268 (68115), 190400 ms
[09:42:36] scan: chap 32 c=35->35, b=3021269->3110376 (89108), 320700 ms
[09:42:36] scan: chap 33 c=36->36, b=3110377->3277395 (167019), 603333 ms
[09:42:36] scan: chap 34 c=37->37, b=3277396->3364789 (87394), 300000 ms
[09:42:36] scan: chap 35 c=38->38, b=3364790->3495144 (130355), 446333 ms
[09:42:36] scan: chap 36 c=39->39, b=3495145->3567010 (71866), 243000 ms
[09:42:36] scan: chap 37 c=40->40, b=3567011->3683035 (116025), 395000 ms
[09:42:36] scan: chap 38 c=41->41, b=3683036->3686674 (3639), 28734 ms
[09:42:36] scan: chap 39 c=42->42, b=3686675->3686726 (52), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 2
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:00:00 (500 ms)
[09:42:36] scan: ignoring title (too short)
[09:42:36] scan: scanning title 3
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:42:33 (2553500 ms)
[09:42:36] pgc_id: 3, pgn: 1: pgc: 000000001F3F1850
[09:42:36] scan: vts=1, ttn=3, cells=0->7, blocks=0->739343, 739344 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 3 has 8 chapters
[09:42:36] scan: chap 1 c=0->0, b=0->43080 (43081), 115166 ms
[09:42:36] scan: chap 2 c=1->1, b=43081->196771 (153691), 521600 ms
[09:42:36] scan: chap 3 c=2->2, b=196772->328548 (131777), 458333 ms
[09:42:36] scan: chap 4 c=3->3, b=328549->452102 (123554), 442000 ms
[09:42:36] scan: chap 5 c=4->4, b=452103->620884 (168782), 577667 ms
[09:42:36] scan: chap 6 c=5->5, b=620885->735754 (114870), 409000 ms
[09:42:36] scan: chap 7 c=6->6, b=735755->739293 (3539), 28734 ms
[09:42:36] scan: chap 8 c=7->7, b=739294->739343 (50), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 4
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:42:22 (2542000 ms)
[09:42:36] pgc_id: 4, pgn: 1: pgc: 000000001F3F1DF0
[09:42:36] scan: vts=1, ttn=4, cells=0->8, blocks=739344->1477764, 738421 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 4 has 9 chapters
[09:42:36] scan: chap 1 c=0->0, b=739344->767719 (28376), 89033 ms
[09:42:36] scan: chap 2 c=1->1, b=767720->889497 (121778), 422667 ms
[09:42:36] scan: chap 3 c=2->2, b=889498->1081676 (192179), 655000 ms
[09:42:36] scan: chap 4 c=3->3, b=1081677->1202336 (120660), 432333 ms
[09:42:36] scan: chap 5 c=4->4, b=1202337->1283626 (81290), 281000 ms
[09:42:36] scan: chap 6 c=5->5, b=1283627->1373812 (90186), 315333 ms
[09:42:36] scan: chap 7 c=6->6, b=1373813->1473895 (100083), 317000 ms
[09:42:36] scan: chap 8 c=7->7, b=1473896->1477738 (3843), 29133 ms
[09:42:36] scan: chap 9 c=8->8, b=1477739->1477764 (26), 500 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 5
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:42:23 (2543500 ms)
[09:42:36] pgc_id: 5, pgn: 1: pgc: 000000001F3F1A90
[09:42:36] scan: vts=1, ttn=5, cells=0->8, blocks=1477765->2214158, 736394 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 5 has 9 chapters
[09:42:36] scan: chap 1 c=0->0, b=1477765->1521139 (43375), 146333 ms
[09:42:36] scan: chap 2 c=1->1, b=1521140->1613923 (92784), 315767 ms
[09:42:36] scan: chap 3 c=2->2, b=1613924->1774404 (160481), 581000 ms
[09:42:36] scan: chap 4 c=3->3, b=1774405->1896417 (122013), 390000 ms
[09:42:36] scan: chap 5 c=4->4, b=1896418->2046774 (150357), 543333 ms
[09:42:36] scan: chap 6 c=5->5, b=2046775->2114934 (68160), 249000 ms
[09:42:36] scan: chap 7 c=6->6, b=2114935->2210524 (95590), 289333 ms
[09:42:36] scan: chap 8 c=7->7, b=2210525->2214104 (3580), 27734 ms
[09:42:36] scan: chap 9 c=8->8, b=2214105->2214158 (54), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 6
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:42:28 (2548333 ms)
[09:42:36] pgc_id: 6, pgn: 1: pgc: 000000001F3F1BB0
[09:42:36] scan: vts=1, ttn=6, cells=0->7, blocks=2214159->2953153, 738995 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 6 has 8 chapters
[09:42:36] scan: chap 1 c=0->0, b=2214159->2271543 (57385), 184567 ms
[09:42:36] scan: chap 2 c=1->1, b=2271544->2411834 (140291), 482867 ms
[09:42:36] scan: chap 3 c=2->2, b=2411835->2582140 (170306), 613000 ms
[09:42:36] scan: chap 4 c=3->3, b=2582141->2704173 (122033), 422000 ms
[09:42:36] scan: chap 5 c=4->4, b=2704174->2875062 (170889), 560667 ms
[09:42:36] scan: chap 6 c=5->5, b=2875063->2949520 (74458), 255667 ms
[09:42:36] scan: chap 7 c=6->6, b=2949521->2953098 (3578), 28567 ms
[09:42:36] scan: chap 8 c=7->7, b=2953099->2953153 (55), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 7
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:42:08 (2528500 ms)
[09:42:36] pgc_id: 7, pgn: 1: pgc: 000000001F3F1CD0
[09:42:36] scan: vts=1, ttn=7, cells=0->8, blocks=2953154->3686726, 733573 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking audio 2
[09:42:36] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[09:42:36] scan: checking audio 3
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 7 has 9 chapters
[09:42:36] scan: chap 1 c=0->0, b=2953154->3021268 (68115), 190400 ms
[09:42:36] scan: chap 2 c=1->1, b=3021269->3110376 (89108), 320700 ms
[09:42:36] scan: chap 3 c=2->2, b=3110377->3277395 (167019), 603333 ms
[09:42:36] scan: chap 4 c=3->3, b=3277396->3364789 (87394), 300000 ms
[09:42:36] scan: chap 5 c=4->4, b=3364790->3495144 (130355), 446333 ms
[09:42:36] scan: chap 6 c=5->5, b=3495145->3567010 (71866), 243000 ms
[09:42:36] scan: chap 7 c=6->6, b=3567011->3683035 (116025), 395000 ms
[09:42:36] scan: chap 8 c=7->7, b=3683036->3686674 (3639), 28734 ms
[09:42:36] scan: chap 9 c=8->8, b=3686675->3686726 (52), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 8
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:01:56 (116967 ms)
[09:42:36] pgc_id: 8, pgn: 1: pgc: 000000001F3F1970
[09:42:36] scan: vts=1, ttn=8, cells=0->1, blocks=3686798->3710653, 23856 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 2
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 3
[09:42:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 8 has 2 chapters
[09:42:36] scan: chap 1 c=0->0, b=3686798->3710636 (23839), 116433 ms
[09:42:36] scan: chap 2 c=1->1, b=3710637->3710653 (17), 533 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 9
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:03:56 (236767 ms)
[09:42:36] pgc_id: 9, pgn: 1: pgc: 000000001F3F1F10
[09:42:36] scan: vts=1, ttn=9, cells=0->5, blocks=3710654->3758986, 48333 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 2
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 3
[09:42:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 9 has 4 chapters
[09:42:36] scan: chap 1 c=0->1, b=3710654->3727560 (16907), 82599 ms
[09:42:36] scan: chap 2 c=2->3, b=3727561->3736281 (8721), 42734 ms
[09:42:36] scan: chap 3 c=4->4, b=3736282->3758972 (22691), 110967 ms
[09:42:36] scan: chap 4 c=5->5, b=3758973->3758986 (14), 500 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 10
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:02:00 (120767 ms)
[09:42:36] pgc_id: 10, pgn: 1: pgc: 000000001F3F1610
[09:42:36] scan: vts=1, ttn=10, cells=0->3, blocks=3758987->3783531, 24545 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 2
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 3
[09:42:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 10 has 3 chapters
[09:42:36] scan: chap 1 c=0->1, b=3758987->3775791 (16805), 82734 ms
[09:42:36] scan: chap 2 c=2->2, b=3775792->3783518 (7727), 37533 ms
[09:42:36] scan: chap 3 c=3->3, b=3783519->3783531 (13), 500 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 11
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:06:25 (385367 ms)
[09:42:36] pgc_id: 11, pgn: 1: pgc: 000000001F3F2030
[09:42:36] scan: vts=1, ttn=11, cells=0->11, blocks=3783532->3862026, 78495 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 2
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 3
[09:42:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 11 has 7 chapters
[09:42:36] scan: chap 1 c=0->1, b=3783532->3799358 (15827), 77133 ms
[09:42:36] scan: chap 2 c=2->3, b=3799359->3803955 (4597), 22533 ms
[09:42:36] scan: chap 3 c=4->5, b=3803956->3821940 (17985), 88000 ms
[09:42:36] scan: chap 4 c=6->7, b=3821941->3832483 (10543), 51900 ms
[09:42:36] scan: chap 5 c=8->9, b=3832484->3836865 (4382), 22000 ms
[09:42:36] scan: chap 6 c=10->10, b=3836866->3862001 (25136), 122867 ms
[09:42:36] scan: chap 7 c=11->11, b=3862002->3862026 (25), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 12
[09:42:36] scan: opening IFO for VTS 1
[09:42:36] scan: duration is 00:14:19 (859867 ms)
[09:42:36] pgc_id: 12, pgn: 1: pgc: 000000001F3F14F0
[09:42:36] scan: vts=1, ttn=12, cells=0->23, blocks=3686798->3862026, 175229 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 2
[09:42:36] scan: audio channel is not active
[09:42:36] scan: checking audio 3
[09:42:36] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[09:42:36] scan: checking subtitle 2
[09:42:36] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[09:42:36] scan: checking subtitle 3
[09:42:36] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[09:42:36] scan: checking subtitle 4
[09:42:36] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[09:42:36] scan: checking subtitle 5
[09:42:36] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[09:42:36] scan: checking subtitle 6
[09:42:36] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[09:42:36] scan: checking subtitle 7
[09:42:36] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[09:42:36] scan: title 12 has 13 chapters
[09:42:36] scan: chap 1 c=0->1, b=3686798->3710653 (23856), 116966 ms
[09:42:36] scan: chap 2 c=2->3, b=3710654->3727560 (16907), 82599 ms
[09:42:36] scan: chap 3 c=4->5, b=3727561->3736281 (8721), 42734 ms
[09:42:36] scan: chap 4 c=6->7, b=3736282->3758986 (22705), 111467 ms
[09:42:36] scan: chap 5 c=8->9, b=3758987->3775791 (16805), 82734 ms
[09:42:36] scan: chap 6 c=10->11, b=3775792->3783531 (7740), 38033 ms
[09:42:36] scan: chap 7 c=12->13, b=3783532->3799358 (15827), 77133 ms
[09:42:36] scan: chap 8 c=14->15, b=3799359->3803955 (4597), 22533 ms
[09:42:36] scan: chap 9 c=16->17, b=3803956->3821940 (17985), 88000 ms
[09:42:36] scan: chap 10 c=18->19, b=3821941->3832483 (10543), 51900 ms
[09:42:36] scan: chap 11 c=20->21, b=3832484->3836865 (4382), 22000 ms
[09:42:36] scan: chap 12 c=22->22, b=3836866->3862001 (25136), 122867 ms
[09:42:36] scan: chap 13 c=23->23, b=3862002->3862026 (25), 1000 ms
[09:42:36] scan: aspect = 1.77778
[09:42:36] scan: scanning title 13
[09:42:36] scan: opening IFO for VTS 2
[09:42:36] scan: duration is 00:00:11 (11266 ms)
[09:42:36] pgc_id: 1, pgn: 1: pgc: 000000001F3F14F0
[09:42:36] scan: vts=2, ttn=1, cells=0->1, blocks=0->3478, 3479 blocks
[09:42:36] scan: checking audio 1
[09:42:36] scan: id=0x80bd, lang=Unknown (AC3), 3cc=und ext=0
[09:42:36] scan: checking subtitle 1
[09:42:36] scan: id=0x20bd, lang=Unknown, 3cc=und ext=0
[09:42:36] scan: title 13 has 2 chapters
[09:42:36] scan: chap 1 c=0->0, b=0->3453 (3454), 10800 ms
[09:42:36] scan: chap 2 c=1->1, b=3454->3478 (25), 467 ms
[09:42:36] scan: aspect = 1.77778
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Suspected RCE Region Protection!!!
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
[09:42:36] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:36] scan: title angle(s) 1
[09:42:37] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:37] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:37] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:37] scan: decoding previews for title 3
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:37] scan: title angle(s) 1
[09:42:37] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:37] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:37] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:37] scan: decoding previews for title 4
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:37] scan: title angle(s) 1
[09:42:37] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:37] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:38] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:38] scan: decoding previews for title 5
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:38] scan: title angle(s) 1
[09:42:38] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:38] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:38] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:38] scan: decoding previews for title 6
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:38] scan: title angle(s) 1
[09:42:38] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:38] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:38] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:38] scan: decoding previews for title 7
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:38] scan: title angle(s) 1
[09:42:38] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[09:42:38] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 8
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 9
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 10
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 11
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 12
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[09:42:39] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] scan: decoding previews for title 13
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[09:42:39] scan: title angle(s) 1
[09:42:39] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 Unknown (AC3) (5.1 ch)
[09:42:39] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[09:42:39] libhb: scan thread found 12 valid title(s)
Encode Log:

Code: Select all

HandBrake svn5912 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
Ram: 32708 MB, 
GPU Information:
  AMD Radeon HD 7900 Series - 9.12.0.0
Screen: 1920x1200
Temp Dir: C:\Users\Xenos\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Xenos\AppData\Roaming\HandBrake\HandBrake\0.0.0.5912

-------------------------------------------
CLI Query:  -i "D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1" -t 6 --angle 1 -c 1-8 -o "M:\TV2\A\Arrow (2012)\S1E04 - An Innocent Man.mkv"  -f mkv  --detelecine --decomb -w 720 --crop 0:0:0:0 --loose-anamorphic  --modulus 2 -e x264 -q 20 --vfr -a 1,1 -E av_aac,copy:ac3 -6 dpl2,auto -R Auto,Auto -B 160,0 -D 0,0 --gain 0,0 --audio-fallback ac3 --subtitle 1 --markers="C:\Users\Xenos\AppData\Local\Temp\S1E04 - An Innocent Man-6-chapters.csv" --h264-level="4.1"  --h264-profile=high  --verbose=1 -P 

[10:49:50] hb_init: starting libhb thread
HandBrake svn5912 (2013120401) - MinGW x86_64 - http://handbrake.fr
8 CPUs detected
Opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1...
[10:49:50] CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
[10:49:50]  - Intel microarchitecture Sandy Bridge
[10:49:50]  - logical processor count: 8
[10:49:50] OpenCL device #1: Advanced Micro Devices, Inc. Tahiti
[10:49:50]  - OpenCL version: 1.2 AMD-APP (1084.4)
[10:49:50]  - driver version: 1084.4 (VM)
[10:49:50]  - device type:    GPU
[10:49:50]  - supported:      YES
[10:49:50] Intel Quick Sync Video support: no
[10:49:50] hb_scan: path=D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1, title_index=6
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1) failed (0000000001cb51c0)
[10:49:50] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[10:49:50] scan: DVD has 13 title(s)
[10:49:50] scan: scanning title 6
[10:49:50] scan: opening IFO for VTS 1
[10:49:50] scan: duration is 00:42:28 (2548333 ms)
[10:49:50] pgc_id: 6, pgn: 1: pgc: 0000000001CBB660
[10:49:50] scan: vts=1, ttn=6, cells=0->7, blocks=2214159->2953153, 738995 blocks
[10:49:50] scan: checking audio 1
[10:49:50] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[10:49:50] scan: checking audio 2
[10:49:50] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[10:49:50] scan: checking audio 3
[10:49:50] scan: audio channel is not active
[10:49:50] scan: checking subtitle 1
[10:49:50] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[10:49:50] scan: checking subtitle 2
[10:49:50] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[10:49:50] scan: checking subtitle 3
[10:49:50] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[10:49:50] scan: checking subtitle 4
[10:49:50] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[10:49:50] scan: checking subtitle 5
[10:49:50] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[10:49:50] scan: checking subtitle 6
[10:49:50] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[10:49:50] scan: checking subtitle 7
[10:49:50] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[10:49:50] scan: title 6 has 8 chapters
[10:49:50] scan: chap 1 c=0->0, b=2214159->2271543 (57385), 184567 ms
[10:49:50] scan: chap 2 c=1->1, b=2271544->2411834 (140291), 482867 ms
[10:49:50] scan: chap 3 c=2->2, b=2411835->2582140 (170306), 613000 ms
[10:49:50] scan: chap 4 c=3->3, b=2582141->2704173 (122033), 422000 ms
[10:49:50] scan: chap 5 c=4->4, b=2704174->2875062 (170889), 560667 ms
[10:49:50] scan: chap 6 c=5->5, b=2875063->2949520 (74458), 255667 ms
[10:49:50] scan: chap 7 c=6->6, b=2949521->2953098 (3578), 28567 ms
[10:49:50] scan: chap 8 c=7->7, b=2953099->2953153 (55), 1000 ms
[10:49:50] scan: aspect = 1.77778
[10:49:50] scan: decoding previews for title 6
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[10:49:50] scan: title angle(s) 1
[10:49:50] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[10:49:50] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[10:49:50] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[10:49:50] libhb: scan thread found 1 valid title(s)
+ title 6:
  + vts 1, ttn 6, cells 0->7 (738995 blocks)
  + duration: 00:42:28
  + size: 720x480, pixel aspect: 32/27, display aspect: 1.78, 23.976 fps
  + autocrop: 0/0/0/0
  + support opencl: yes
  + support hwd: no
  + chapters:
    + 1: cells 0->0, 57385 blocks, duration 00:03:05
    + 2: cells 1->1, 140291 blocks, duration 00:08:03
    + 3: cells 2->2, 170306 blocks, duration 00:10:13
    + 4: cells 3->3, 122033 blocks, duration 00:07:02
    + 5: cells 4->4, 170889 blocks, duration 00:09:21
    + 6: cells 5->5, 74458 blocks, duration 00:04:16
    + 7: cells 6->6, 3578 blocks, duration 00:00:29
    + 8: cells 7->7, 55 blocks, duration 00:00:01
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 384000bps
    + 2, Portugues (AC3) (Dolby Surround) (iso639-2: por), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Chinese (iso639-2: zho) (Bitmap)(VOBSUB)
    + 3, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 4, Korean (iso639-2: kor) (Bitmap)(VOBSUB)
    + 5, Portugues (iso639-2: por) (Bitmap)(VOBSUB)
    + 6, Espanol (iso639-2: spa) (Bitmap)(VOBSUB)
    + 7, Thai (iso639-2: tha) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\Xenos\AppData\Local\Temp\S1E04 - An Innocent Man-6-chapters.csv
[10:49:50] 1 job(s) to process
[10:49:50] starting job
[10:49:50] yadif thread started for segment 0
[10:49:50] yadif thread started for segment 1
[10:49:50] yadif thread started for segment 2
[10:49:50] yadif thread started for segment 3
[10:49:50] yadif thread started for segment 4
[10:49:50] yadif thread started for segment 5
[10:49:50] yadif thread started for segment 6
[10:49:50] yadif thread started for segment 7
[10:49:50] decomb filter thread started for segment 0
[10:49:50] decomb filter thread started for segment 1
[10:49:50] decomb filter thread started for segment 2
[10:49:50] decomb filter thread started for segment 3
[10:49:50] decomb filter thread started for segment 4
[10:49:50] decomb filter thread started for segment 5
[10:49:50] decomb filter thread started for segment 6
[10:49:50] decomb filter thread started for segment 7
[10:49:50] decomb check thread started for segment 0
[10:49:50] decomb check thread started for segment 1
[10:49:50] decomb check thread started for segment 2
[10:49:50] decomb check thread started for segment 3
[10:49:50] decomb check thread started for segment 4
[10:49:50] decomb check thread started for segment 5
[10:49:50] decomb check thread started for segment 6
[10:49:50] decomb check thread started for segment 7
[10:49:50] mask filter thread started for segment 0
[10:49:50] mask filter thread started for segment 1
[10:49:50] mask filter thread started for segment 2
[10:49:50] mask filter thread started for segment 3
[10:49:50] mask filter thread started for segment 4
[10:49:50] mask filter thread started for segment 5
[10:49:50] mask filter thread started for segment 6
[10:49:50] mask filter thread started for segment 7
[10:49:50] mask erode thread started for segment 0
[10:49:50] mask erode thread started for segment 1
[10:49:50] mask erode thread started for segment 2
[10:49:50] mask erode thread started for segment 3
[10:49:50] mask erode thread started for segment 4
[10:49:50] mask erode thread started for segment 5
[10:49:50] mask erode thread started for segment 6
[10:49:50] mask erode thread started for segment 7
[10:49:50] mask dilate thread started for segment 0
[10:49:50] mask dilate thread started for segment 1
[10:49:50] mask dilate thread started for segment 2
[10:49:50] mask dilate thread started for segment 3
[10:49:50] mask dilate thread started for segment 4
[10:49:50] mask dilate thread started for segment 5
[10:49:50] mask dilate thread started for segment 6
[10:49:50] mask dilate thread started for segment 7
[10:49:50] sync: expecting 61098 video frames
[10:49:50] job configuration:
[10:49:50]  * source
[10:49:50]    + D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1
[10:49:50]    + title 6, chapter(s) 1 to 8
[10:49:50]  * destination
[10:49:50]    + M:\TV2\A\Arrow (2012)\S1E04 - An Innocent Man.mkv
[10:49:50]    + container: Matroska (avformat)
[10:49:50]      + chapter markers
[10:49:50]  * video track
[10:49:50]    + decoder: mpeg2
[10:49:50]      + bitrate 7500 kbps
[10:49:50]    + filters
[10:49:50]      + Detelecine (pullup) (default settings)
[10:49:50]      + Decomb (default settings)
[10:49:50]      + Framerate Shaper (0:27000000:1126125)
[10:49:50]        + frame rate: same as source (around 23.976 fps)
[10:49:50]      + Crop and Scale (720:480:0:0:0:0)
[10:49:50]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[10:49:50]    + loose anamorphic
[10:49:50]      + storage dimensions: 720 * 480, mod 2
[10:49:50]      + pixel aspect ratio: 32 / 27
[10:49:50]      + display dimensions: 853 * 480
[10:49:50]    + encoder: H.264 (x264)
[10:49:50]      + h264 profile: high
[10:49:50]      + h264 level: 4.1
[10:49:50]      + quality: 20.00 (RF)
[10:49:50]  * subtitle track 1, English (track 1, id 0x21bd) Picture [VOBSUB] -> Passthrough
[10:49:50]  * audio track 1
[10:49:50]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[10:49:50]      + bitrate: 384 kbps, samplerate: 48000 Hz
[10:49:50]    + mixdown: Dolby Pro Logic II
[10:49:50]    + encoder: AAC (avcodec)
[10:49:50]      + bitrate: 160 kbps, samplerate: 48000 Hz
[10:49:50]  * audio track 2
[10:49:50]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[10:49:50]      + bitrate: 384 kbps, samplerate: 48000 Hz
[10:49:50]    + AC3 Passthru
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[10:49:50] encx264: min-keyint: 24, keyint: 240
[10:49:50] encx264: encoding at constant RF 20.000000
[10:49:50] encx264: unparsed options: level=4.1:vbv-bufsize=78125:vbv-maxrate=62500
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
[10:49:50] reader: first SCR 146 id 0xe0 DTS 1092
[10:49:50] mpeg2: "Chapter 1" (1) at frame 0 time 3003
x264 [info]: profile High, level 4.1
[10:49:50] sync: first pts is 3003
[10:49:50] Scaling With OpenCL
[10:49:50] Using Zero Copy
[10:50:36] mpeg2: "Chapter 2" (2) at frame 4430 time 16630614
[10:52:29] mpeg2: "Chapter 3" (3) at frame 16020 time 60132072
[10:54:49] mpeg2: "Chapter 4" (4) at frame 30733 time 115357242
[10:56:27] mpeg2: "Chapter 5" (5) at frame 40862 time 153375222
[10:58:45] mpeg2: "Chapter 6" (6) at frame 54319 time 203885682
[10:59:46] mpeg2: "Chapter 7" (7) at frame 60456 time 226918692
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
[10:59:48] reader: done. 1 scr changes
[10:59:48] mpeg2: "Chapter 8" (8) at frame 61144 time 229492263
[10:59:49] work: average encoding speed for job is 102.181122 fps
[10:59:49] sync: got 61167 frames, 61098 expected
[10:59:49] decomb: deinterlaced 1 | blended 6 | unfiltered 61156 | total 61163
[10:59:49] render: lost time: 9009 (0 frames)
[10:59:49] render: gained time: 9009 (12 frames) (0 not accounted for)
[10:59:49] mpeg2 done: 61167 frames
x264 [info]: frame I:899   Avg QP:15.98  size: 31041
x264 [info]: frame P:25965 Avg QP:19.70  size:  7986
x264 [info]: frame B:34299 Avg QP:21.67  size:  1811
x264 [info]: consecutive B-frames: 14.9% 25.5% 16.6% 43.1%
x264 [info]: mb I  I16..4: 10.4% 83.0%  6.6%
x264 [info]: mb P  I16..4:  1.6%  5.7%  0.5%  P16..4: 47.0% 16.6% 11.1%  0.0%  0.0%    skip:17.5%
x264 [info]: mb B  I16..4:  0.1%  0.2%  0.0%  B16..8: 40.3%  2.8%  0.5%  direct: 1.8%  skip:54.3%  L0:41.4% L1:51.2% BI: 7.4%
x264 [info]: 8x8 transform intra:76.2% inter:83.9%
x264 [info]: coded y,uvDC,uvAC intra: 64.7% 76.8% 40.2% inter: 18.6% 26.7% 1.4%
x264 [info]: i16 v,h,dc,p: 40% 15% 11% 35%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 26% 14% 28%  4%  5%  7%  5%  7%  5%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 31% 14% 14%  6%  9% 10%  6%  7%  4%
x264 [info]: i8c dc,h,v,p: 51% 17% 25%  7%
x264 [info]: Weighted P-Frames: Y:4.1% UV:2.3%
x264 [info]: ref P L0: 60.4% 12.0% 19.3%  8.0%  0.3%
x264 [info]: ref B L0: 84.4% 13.5%  2.1%
x264 [info]: ref B L1: 94.8%  5.2%
x264 [info]: kb/s:932.65
[10:59:49] mux: track 0, 61163 frames, 297339185 bytes, 932.51 kbps, fifo 2048
[10:59:49] mux: track 1, 119571 frames, 50999440 bytes, 159.94 kbps, fifo 4096
[10:59:49] mux: track 2, 79715 frames, 122442240 bytes, 384.00 kbps, fifo 4096
[10:59:49] mux: track 3, 583 frames, 1065428 bytes, 3.34 kbps, fifo 32
From Windows Event Log:

Code: Select all

Faulting application name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x529ea5f7
Faulting module name: HandBrakeCLI.exe, version: 0.0.0.0, time stamp: 0x529ea5f7
Exception code: 0xc0000005
Fault offset: 0x0000000000059967
Faulting process id: 0x4f0
Faulting application start time: 0x01cef363f681a21a
Faulting application path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Faulting module path: C:\Program Files\Handbrake\HandBrakeCLI.exe
Report Id: 9a8b2be1-5f58-11e3-bdd4-005056c00008
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

Starting to suspect it's some kind of finalization bug in the new muxers. John might have a better idea. Is the file playable? It looks like it may have crashed just after muxing.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

s55 wrote:Starting to suspect it's some kind of finalization bug in the new muxers. John might have a better idea. Is the file playable? It looks like it may have crashed just after muxing.
Thank you for responding. That's a good thought. Yes, the file is playable in it's entirety. Is is also the exact same size as the one created by the older version. Also, after the crash, HandBrake will continue on normally to the next file.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

The Windows GUI is just launching separate instances of HandBrakeCLI, so it's basically getting process isolation for free. 1 Crash won't bring down the queue or GUI
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

Sure, I get that. I guess I was just thinking out loud (at it were) that, even though it crashes, the file is OK and HandBrake continues on, I won't be stuck using a back-level version while I am waiting for a fix.

Thanks.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

Does anyone have a sample file they could share that triggers this. Nothing I have seems to trigger it.

Also, can you both turn up the verbosity in Tools -> Options -> Advanced and provide new logs please.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

It isn't triggered by a particular file. That is, I can re-run it with the same file and it will work fine. I haven't seen any pattern yet. I will be happy to turn up the verbosity and provide another log. It will be forthcoming.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

OK, this time it crashed right away (3rd file).

Code: Select all

HandBrake svn5912 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
Ram: 32708 MB, 
GPU Information:
  AMD Radeon HD 7900 Series - 9.12.0.0
Screen: 1920x1200
Temp Dir: C:\Users\Xenos\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Xenos\AppData\Roaming\HandBrake\HandBrake\0.0.0.5912

-------------------------------------------

CLI Query:  -i "D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3" -t 9 --angle 1 -c 1-4 -o "M:\TV2\A\Arrow (2012)\Special Features\S1D3-01 - Deleted Scenes.mkv"  -f mkv  --detelecine --decomb -w 720 --crop 0:0:0:0 --loose-anamorphic  --modulus 2 -e x264 -q 20 --vfr -a 1,1 -E av_aac,copy:ac3 -6 dpl2,auto -R Auto,Auto -B 160,0 -D 0,0 --gain 0,0 --audio-fallback ac3 --subtitle 1 --markers="C:\Users\Xenos\AppData\Local\Temp\S1D3-01 - Deleted Scenes-9-chapters.csv" --h264-level="4.1"  --h264-profile=high  --verbose=2 -P 

[16:23:34] hb_init: starting libhb thread
[16:23:34] thread 1f8e400 started ("libhb")
HandBrake svn5912 (2013120401) - MinGW x86_64 - http://handbrake.fr
8 CPUs detected
Opening D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3...
[16:23:34] CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
[16:23:34]  - Intel microarchitecture Sandy Bridge
[16:23:34]  - logical processor count: 8
[16:23:34] OpenCL device #1: Advanced Micro Devices, Inc. Tahiti
[16:23:34]  - OpenCL version: 1.2 AMD-APP (1084.4)
[16:23:34]  - driver version: 1084.4 (VM)
[16:23:34]  - device type:    GPU
[16:23:34]  - supported:      YES
[16:23:34] Intel Quick Sync Video support: no
[16:23:34] hb_scan: path=D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3, title_index=9
[16:23:34] thread 334a60 started ("scan")
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3) failed (00000000003351c0)
[16:23:34] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[16:23:34] scan: DVD has 10 title(s)
[16:23:34] scan: scanning title 9
[16:23:34] scan: opening IFO for VTS 1
[16:23:34] scan: duration is 00:01:26 (86967 ms)
[16:23:34] pgc_id: 9, pgn: 1: pgc: 000000000033B960
[16:23:34] scan: vts=1, ttn=9, cells=0->5, blocks=3690257->3707950, 17694 blocks
[16:23:34] scan: checking audio 1
[16:23:34] scan: audio channel is not active
[16:23:34] scan: checking audio 2
[16:23:34] scan: audio channel is not active
[16:23:34] scan: checking audio 3
[16:23:34] scan: id=0x82bd, lang=English (AC3), 3cc=eng ext=0
[16:23:34] scan: checking subtitle 1
[16:23:34] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[16:23:34] scan: checking subtitle 2
[16:23:34] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[16:23:34] scan: checking subtitle 3
[16:23:34] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[16:23:34] scan: checking subtitle 4
[16:23:34] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[16:23:34] scan: checking subtitle 5
[16:23:34] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[16:23:34] scan: checking subtitle 6
[16:23:34] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[16:23:34] scan: checking subtitle 7
[16:23:34] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[16:23:34] scan: title 9 has 4 chapters
[16:23:34] scan: chap 1 c=0->1, b=3690257->3699367 (9111), 44700 ms
[16:23:34] scan: chap 2 c=2->3, b=3699368->3702419 (3052), 15033 ms
[16:23:34] scan: chap 3 c=4->4, b=3702420->3707932 (5513), 26734 ms
[16:23:34] scan: chap 4 c=5->5, b=3707933->3707950 (18), 533 ms
[16:23:34] scan: aspect = 1.77778
[16:23:34] scan: decoding previews for title 9
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[16:23:34] scan: title angle(s) 1
[16:23:34] scan: preview 1
[16:23:34] fifo_close: trashing 0 buffer(s)
[16:23:34] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[16:23:34] scan: preview 2
[16:23:34] scan: preview 3
[16:23:34] scan: preview 4
[16:23:34] scan: preview 5
[16:23:34] scan: preview 6
[16:23:34] scan: preview 7
[16:23:34] scan: preview 8
[16:23:34] scan: preview 9
[16:23:34] scan: preview 10
[16:23:34] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[16:23:34] Freed 1 buffers of size 2048
[16:23:34] Freed 1 buffers of size 4096
[16:23:34] Freed 1 buffers of size 1048576
[16:23:34] Allocated 1054720 bytes of buffers on this pass and Freed 1054720 bytes, 0 bytes leaked
[16:23:34] thread 334a60 exited ("scan")
[16:23:34] thread 334a60 joined ("scan")
[16:23:34] libhb: scan thread found 1 valid title(s)
+ title 9:
  + vts 1, ttn 9, cells 0->5 (17694 blocks)
  + duration: 00:01:26
  + size: 720x480, pixel aspect: 32/27, display aspect: 1.78, 23.976 fps
  + autocrop: 0/0/0/0
  + support opencl: yes
  + support hwd: no
  + chapters:
    + 1: cells 0->1, 9111 blocks, duration 00:00:45
    + 2: cells 2->3, 3052 blocks, duration 00:00:15
    + 3: cells 4->4, 5513 blocks, duration 00:00:27
    + 4: cells 5->5, 18 blocks, duration 00:00:01
  + audio tracks:
    + 1, English (AC3) (2.0 ch) (iso639-2: eng), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Chinese (iso639-2: zho) (Bitmap)(VOBSUB)
    + 3, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 4, Korean (iso639-2: kor) (Bitmap)(VOBSUB)
    + 5, Portugues (iso639-2: por) (Bitmap)(VOBSUB)
    + 6, Espanol (iso639-2: spa) (Bitmap)(VOBSUB)
    + 7, Thai (iso639-2: tha) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\Xenos\AppData\Local\Temp\S1D3-01 - Deleted Scenes-9-chapters.csv
[16:23:34] thread 334a60 started ("work")
[16:23:34] 1 job(s) to process
[16:23:34] starting job
[16:23:34] thread 337610 started ("yadif_filter_segment")
[16:23:34] thread 337a90 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 0
[16:23:34] yadif thread started for segment 1
[16:23:34] thread 3377b0 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 2
[16:23:34] thread 387b10 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 3
[16:23:34] thread 387550 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 4
[16:23:34] thread 387cb0 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 5
[16:23:34] thread 62d58d0 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 6
[16:23:34] thread 62d5d50 started ("yadif_filter_segment")
[16:23:34] yadif thread started for segment 7
[16:23:34] thread 335210 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 0
[16:23:34] thread 35c520 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 1
[16:23:34] thread 35c6c0 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 2
[16:23:34] thread 35c860 started ("decomb_filter_segment")
[16:23:34] thread 35ca00 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 3
[16:23:34] decomb filter thread started for segment 4
[16:23:34] thread 35cba0 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 5
[16:23:34] thread 35cd40 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 6
[16:23:34] thread 35cee0 started ("decomb_filter_segment")
[16:23:34] decomb filter thread started for segment 7
[16:23:34] thread 35d080 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 0
[16:23:34] thread 35d220 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 1
[16:23:34] thread 35d3c0 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 2
[16:23:34] thread 35d560 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 3
[16:23:34] thread 35d700 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 4
[16:23:34] thread 35d8a0 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 5
[16:23:34] thread 35da40 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 6
[16:23:34] thread 35dbe0 started ("decomb_check_segment")
[16:23:34] decomb check thread started for segment 7
[16:23:34] thread 35dd80 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 0
[16:23:34] thread 35df20 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 1
[16:23:34] thread 35e0c0 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 2
[16:23:34] thread 35e260 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 3
[16:23:34] thread 6093050 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 4
[16:23:34] thread 60931f0 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 5
[16:23:34] thread 6093390 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 6
[16:23:34] thread 6093530 started ("mask_filter_segment")
[16:23:34] mask filter thread started for segment 7
[16:23:34] thread 60936d0 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 0
[16:23:34] thread 6093870 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 1
[16:23:34] thread 6093a10 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 2
[16:23:34] thread 6093bb0 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 3
[16:23:34] thread 6093d50 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 4
[16:23:34] thread 6093ef0 started ("mask_erode_segment")
[16:23:34] thread 6094090 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 5
[16:23:34] mask erode thread started for segment 6
[16:23:34] thread 6094230 started ("mask_erode_segment")
[16:23:34] mask erode thread started for segment 7
[16:23:34] thread 60943d0 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 0
[16:23:34] thread 6094570 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 1
[16:23:34] thread 6094710 started ("mask_dilate_segment")
[16:23:34] thread 60948b0 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 2
[16:23:34] mask dilate thread started for segment 3
[16:23:34] thread 6094a50 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 4
[16:23:34] thread 6094bf0 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 5
[16:23:34] thread 6094d90 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 6
[16:23:34] thread 6095c50 started ("mask_dilate_segment")
[16:23:34] mask dilate thread started for segment 7
[16:23:34] work: sanitizing track 1 mixdown Dolby Pro Logic II to Stereo
[16:23:34] sync: expecting 2085 video frames
[16:23:34] job configuration:
[16:23:34]  * source
[16:23:34]    + D:\DVD\arrow\S1D3\ARROW_SEASON_1_DISC_3
[16:23:34]    + title 9, chapter(s) 1 to 4
[16:23:34]  * destination
[16:23:34]    + M:\TV2\A\Arrow (2012)\Special Features\S1D3-01 - Deleted Scenes.mkv
[16:23:34]    + container: Matroska (avformat)
[16:23:34]      + chapter markers
[16:23:34]  * video track
[16:23:34]    + decoder: mpeg2
[16:23:34]      + bitrate 7500 kbps
[16:23:34]    + filters
[16:23:34]      + Detelecine (pullup) (default settings)
[16:23:34]      + Decomb (default settings)
[16:23:34]      + Framerate Shaper (0:27000000:1126125)
[16:23:34]        + frame rate: same as source (around 23.976 fps)
[16:23:34]      + Crop and Scale (720:480:0:0:0:0)
[16:23:34]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[16:23:34]    + loose anamorphic
[16:23:34]      + storage dimensions: 720 * 480, mod 2
[16:23:34]      + pixel aspect ratio: 32 / 27
[16:23:34]      + display dimensions: 853 * 480
[16:23:34]    + encoder: H.264 (x264)
[16:23:34]      + h264 profile: high
[16:23:34]      + h264 level: 4.1
[16:23:34]      + quality: 20.00 (RF)
[16:23:34]  * subtitle track 1, English (track 1, id 0x21bd) Picture [VOBSUB] -> Passthrough
[16:23:34]  * audio track 1
[16:23:34]    + decoder: English (AC3) (2.0 ch) (track 1, id 0x82bd)
[16:23:34]      + bitrate: 192 kbps, samplerate: 48000 Hz
[16:23:34]    + mixdown: Stereo
[16:23:34]    + encoder: AAC (avcodec)
[16:23:34]      + bitrate: 160 kbps, samplerate: 48000 Hz
[16:23:34]  * audio track 2
[16:23:34]    + decoder: English (AC3) (2.0 ch) (track 1, id 0x82bd)
[16:23:34]      + bitrate: 192 kbps, samplerate: 48000 Hz
[16:23:34]    + AC3 Passthru
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[16:23:34] thread 6095f90 started ("Reader")
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions:[16:23:34] thread 6096130 started ("Detelecine (pullup)")
 1 2 3 4 5 6 7 8[16:23:34] thread 60962d0 started ("Decomb")
[16:23:34] thread 6096470 started ("Framerate Shaper")
[16:23:34] thread 6096610 started ("Crop and Scale")
[16:23:34] thread 60967b0 started ("AudioSynchronization")
[16:23:34] thread 6096950 started ("AudioSynchronization")
[16:23:34] thread 6096af0 started ("MPEG-2 decoder (libmpeg2)")
[16:23:34] thread 6096c90 started ("VOBSUB decoder")
[16:23:34] encx264: min-keyint: 24, keyint: 240
[16:23:34] encx264: encoding at constant RF 20.000000
[16:23:34] encx264: unparsed options: level=4.1:vbv-bufsize=78125:vbv-maxrate=62500
[16:23:34] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
[16:23:34] reader: first SCR 146 id 0xe0 DTS 1092
[16:23:34] mpeg2: "Chapter 1" (1) at frame 0 time 3003
x264 [info]: profile High, level 4.1
[16:23:34] thread 61a04b0 started ("H.264/AVC encoder (libx264)")
[16:23:34] thread 61a0650 started ("AC3 decoder")
[16:23:34] thread 61a07f0 started ("AVCodec Audio encoder (libavcodec)")
[16:23:34] thread 61a0990 started ("AC3 decoder")
[16:23:34] thread 61a0b30 started ("Video Synchronization")
[16:23:34] sync: first pts is 3003
[16:23:34] Writing Metadata to output file...
[16:23:34] thread 61a0cd0 started ("Muxer")
[16:23:34] thread 61a0e70 started ("Muxer")
[16:23:34] thread 61a1010 started ("Muxer")
[16:23:34] Scaling With OpenCL
[16:23:34] Using Zero Copy
[16:23:42] mpeg2: "Chapter 2" (2) at frame 1074 time 4030023
[16:23:44] sync: adding 72 ms of silence to audio 0x82bd  start 5386354, next 5379840
[16:23:44] sync: audio 0x82bd time went backwards 23 ms, dropped 1 frames (start 5389234, next 5391360)
[16:23:44] sync: adding 72 ms of silence to audio 0x82bd  start 5386354, next 5379840
[16:23:45] mpeg2: "Chapter 3" (3) at frame 1435 time 5388279
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
[16:23:49] reader: done. 3 scr changes
[16:23:49] thread 6095f90 exited ("Reader")
[16:23:49] mpeg2: "Chapter 4" (4) at frame 2078 time 7796261
[16:23:50] work: average encoding speed for job is 137.284668 fps
[16:23:50] thread 6096130 exited ("Detelecine (pullup)")
[16:23:50] thread 60962d0 exited ("Decomb")
[16:23:50] thread 61a0990 exited ("AC3 decoder")
[16:23:50] thread 6096950 exited ("AudioSynchronization")
[16:23:50] thread 6096c90 exited ("VOBSUB decoder")
[16:23:50] thread 61a0e70 exited ("Muxer")
[16:23:50] thread 60967b0 exited ("AudioSynchronization")
[16:23:50] thread 61a0650 exited ("AC3 decoder")
[16:23:50] thread 6096470 exited ("Framerate Shaper")
[16:23:50] thread 61a0cd0 exited ("Muxer")
[16:23:50] thread 61a07f0 exited ("AVCodec Audio encoder (libavcodec)")
[16:23:50] thread 61a0b30 exited ("Video Synchronization")
[16:23:50] thread 61a1010 exited ("Muxer")
[16:23:50] thread 6096af0 exited ("MPEG-2 decoder (libmpeg2)")
[16:23:50] thread 61a0b30 joined ("Video Synchronization")
[16:23:50] sync: got 2089 frames, 2085 expected
[16:23:50] thread 6096130 joined ("Detelecine (pullup)")
[16:23:50] thread 60962d0 joined ("Decomb")
[16:23:50] decomb: deinterlaced 0 | blended 0 | unfiltered 2088 | total 2088
[16:23:50] thread 387b10 exited ("yadif_filter_segment")
[16:23:50] thread 387550 exited ("yadif_filter_segment")
[16:23:50] thread 3377b0 exited ("yadif_filter_segment")
[16:23:50] thread 62d5d50 exited ("yadif_filter_segment")
[16:23:50] thread 387cb0 exited ("yadif_filter_segment")
[16:23:50] thread 337610 exited ("yadif_filter_segment")
[16:23:50] thread 62d58d0 exited ("yadif_filter_segment")
[16:23:50] thread 337a90 exited ("yadif_filter_segment")
[16:23:50] thread 337610 joined ("yadif_filter_segment")
[16:23:50] thread 337a90 joined ("yadif_filter_segment")
[16:23:50] thread 3377b0 joined ("yadif_filter_segment")
[16:23:50] thread 387b10 joined ("yadif_filter_segment")
[16:23:50] thread 387550 joined ("yadif_filter_segment")
[16:23:50] thread 387cb0 joined ("yadif_filter_segment")
[16:23:50] thread 62d58d0 joined ("yadif_filter_segment")
[16:23:50] thread 62d5d50 joined ("yadif_filter_segment")
[16:23:50] thread 35cd40 exited ("decomb_filter_segment")
[16:23:50] thread 335210 exited ("decomb_filter_segment")
[16:23:50] thread 35ca00 exited ("decomb_filter_segment")
[16:23:50] thread 35cee0 exited ("decomb_filter_segment")
[16:23:50] thread 35c860 exited ("decomb_filter_segment")
[16:23:50] thread 35c6c0 exited ("decomb_filter_segment")
[16:23:50] thread 35cba0 exited ("decomb_filter_segment")
[16:23:50] thread 35c520 exited ("decomb_filter_segment")
[16:23:50] thread 335210 joined ("decomb_filter_segment")
[16:23:50] thread 35c520 joined ("decomb_filter_segment")
[16:23:50] thread 35c6c0 joined ("decomb_filter_segment")
[16:23:50] thread 35c860 joined ("decomb_filter_segment")
[16:23:50] thread 35ca00 joined ("decomb_filter_segment")
[16:23:50] thread 35cba0 joined ("decomb_filter_segment")
[16:23:50] thread 35cd40 joined ("decomb_filter_segment")
[16:23:50] thread 35cee0 joined ("decomb_filter_segment")
[16:23:50] thread 35dbe0 exited ("decomb_check_segment")
[16:23:50] thread 35d700 exited ("decomb_check_segment")
[16:23:50] thread 35d560 exited ("decomb_check_segment")
[16:23:50] thread 35d080 exited ("decomb_check_segment")
[16:23:50] thread 35d220 exited ("decomb_check_segment")
[16:23:50] thread 35da40 exited ("decomb_check_segment")
[16:23:50] thread 35d3c0 exited ("decomb_check_segment")
[16:23:50] thread 35d8a0 exited ("decomb_check_segment")
[16:23:50] thread 35d080 joined ("decomb_check_segment")
[16:23:50] thread 35d220 joined ("decomb_check_segment")
[16:23:50] thread 35d3c0 joined ("decomb_check_segment")
[16:23:50] thread 35d560 joined ("decomb_check_segment")
[16:23:50] thread 35d700 joined ("decomb_check_segment")
[16:23:50] thread 35d8a0 joined ("decomb_check_segment")
[16:23:50] thread 35da40 joined ("decomb_check_segment")
[16:23:50] thread 35dbe0 joined ("decomb_check_segment")
[16:23:50] thread 6093050 exited ("mask_filter_segment")
[16:23:50] thread 6093530 exited ("mask_filter_segment")
[16:23:50] thread 35e260 exited ("mask_filter_segment")
[16:23:50] thread 6093390 exited ("mask_filter_segment")
[16:23:50] thread 35df20 exited ("mask_filter_segment")
[16:23:50] thread 60931f0 exited ("mask_filter_segment")
[16:23:50] thread 35dd80 exited ("mask_filter_segment")
[16:23:50] thread 35e0c0 exited ("mask_filter_segment")
[16:23:50] thread 35dd80 joined ("mask_filter_segment")
[16:23:50] thread 35df20 joined ("mask_filter_segment")
[16:23:50] thread 35e0c0 joined ("mask_filter_segment")
[16:23:50] thread 35e260 joined ("mask_filter_segment")
[16:23:50] thread 6093050 joined ("mask_filter_segment")
[16:23:50] thread 60931f0 joined ("mask_filter_segment")
[16:23:50] thread 6093390 joined ("mask_filter_segment")
[16:23:50] thread 6093530 joined ("mask_filter_segment")
[16:23:50] thread 6094090 exited ("mask_erode_segment")
[16:23:50] thread 6094230 exited ("mask_erode_segment")
[16:23:50] thread 60936d0 exited ("mask_erode_segment")
[16:23:50] thread 6093870 exited ("mask_erode_segment")
[16:23:50] thread 6093ef0 exited ("mask_erode_segment")
[16:23:50] thread 6093d50 exited ("mask_erode_segment")
[16:23:50] thread 6093a10 exited ("mask_erode_segment")
[16:23:50] thread 6093bb0 exited ("mask_erode_segment")
[16:23:50] thread 60936d0 joined ("mask_erode_segment")
[16:23:50] thread 6093870 joined ("mask_erode_segment")
[16:23:50] thread 6093a10 joined ("mask_erode_segment")
[16:23:50] thread 6093bb0 joined ("mask_erode_segment")
[16:23:50] thread 6093d50 joined ("mask_erode_segment")
[16:23:50] thread 6093ef0 joined ("mask_erode_segment")
[16:23:50] thread 6094090 joined ("mask_erode_segment")
[16:23:50] thread 6094230 joined ("mask_erode_segment")
[16:23:50] thread 6094a50 exited ("mask_dilate_segment")
[16:23:50] thread 6094d90 exited ("mask_dilate_segment")
[16:23:50] thread 6094570 exited ("mask_dilate_segment")
[16:23:50] thread 60948b0 exited ("mask_dilate_segment")
[16:23:50] thread 6094710 exited ("mask_dilate_segment")
[16:23:50] thread 6095c50 exited ("mask_dilate_segment")
[16:23:50] thread 60943d0 exited ("mask_dilate_segment")
[16:23:50] thread 6094bf0 exited ("mask_dilate_segment")
[16:23:50] thread 60943d0 joined ("mask_dilate_segment")
[16:23:50] thread 6094570 joined ("mask_dilate_segment")
[16:23:50] thread 6094710 joined ("mask_dilate_segment")
[16:23:50] thread 60948b0 joined ("mask_dilate_segment")
[16:23:50] thread 6094a50 joined ("mask_dilate_segment")
[16:23:50] thread 6094bf0 joined ("mask_dilate_segment")
[16:23:50] thread 6094d90 joined ("mask_dilate_segment")
[16:23:50] thread 6095c50 joined ("mask_dilate_segment")
[16:23:50] thread 6096470 joined ("Framerate Shaper")
[16:23:50] render: lost time: 0 (0 frames)
[16:23:50] render: gained time: 0 (0 frames) (0 not accounted for)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] thread 6096610 exited ("Crop and Scale")
[16:23:50] thread 6096610 joined ("Crop and Scale")
[16:23:50] thread 60967b0 joined ("AudioSynchronization")
[16:23:50] thread 6096950 joined ("AudioSynchronization")
[16:23:50] thread 6096af0 joined ("MPEG-2 decoder (libmpeg2)")
[16:23:50] mpeg2 done: 2089 frames
[16:23:50] thread 6096c90 joined ("VOBSUB decoder")
[16:23:50] thread 61a04b0 exited ("H.264/AVC encoder (libx264)")
[16:23:50] thread 61a04b0 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:39    Avg QP:15.69  size: 28394
x264 [info]: frame P:901   Avg QP:19.53  size:  8588
x264 [info]: frame B:1148  Avg QP:20.61  size:  2096
x264 [info]: consecutive B-frames: 15.2% 28.4% 18.1% 38.3%
x264 [info]: mb I  I16..4: 15.0% 80.9%  4.0%
x264 [info]: mb P  I16..4:  1.7%  7.5%  0.7%  P16..4: 44.5% 17.9%  9.8%  0.0%  0.0%    skip:17.7%
x264 [info]: mb B  I16..4:  0.1%  0.3%  0.0%  B16..8: 37.2%  3.9%  0.7%  direct: 2.0%  skip:55.8%  L0:40.6% L1:48.3% BI:11.2%
x264 [info]: 8x8 transform intra:77.0% inter:83.0%
x264 [info]: coded y,uvDC,uvAC intra: 71.3% 80.0% 37.1% inter: 21.0% 27.1% 1.0%
x264 [info]: i16 v,h,dc,p: 38%  7%  5% 50%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 30% 16% 22%  4%  5%  7%  5%  6%  5%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 43% 12% 12%  4%  7%  8%  5%  6%  3%
x264 [info]: i8c dc,h,v,p: 50% 16% 27%  6%
x264 [info]: Weighted P-Frames: Y:1.2% UV:0.9%
x264 [info]: ref P L0: 64.9% 13.9% 15.8%  5.2%  0.1%
x264 [info]: ref B L0: 88.7% 10.1%  1.2%
x264 [info]: ref B L1: 95.9%  4.1%
x264 [info]: kb/s:1034.27
[16:23:50] thread 61a0650 joined ("AC3 decoder")
[16:23:50] thread 61a07f0 joined ("AVCodec Audio encoder (libavcodec)")
[16:23:50] encavcodeca: closing libavcodec
[16:23:50] thread 61a0990 joined ("AC3 decoder")
[16:23:50] thread 61a0cd0 joined ("Muxer")
[16:23:50] thread 61a0e70 joined ("Muxer")
[16:23:50] thread 61a1010 joined ("Muxer")
[16:23:50] mux: file size, 15181927 bytes
[16:23:50] mux: track 0, 2088 frames, 11249279 bytes, 1033.88 kbps, fifo 2048
[16:23:50] mux: track 1, 4080 frames, 1746412 bytes, 160.51 kbps, fifo 4096
[16:23:50] mux: track 2, 2721 frames, 2089728 bytes, 192.06 kbps, fifo 4096
[16:23:50] mux: track 3, 19 frames, 31524 bytes, 2.90 kbps, fifo 16
[16:23:50] mux: overhead, 7.30 bytes per frame
[16:23:50] thread 6095f90 joined ("Reader")
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] fifo_close: trashing 0 buffer(s)
[16:23:50] Freed 320 buffers of size 1024
[16:23:50] Freed 32 buffers of size 2048
[16:23:50] Freed 32 buffers of size 4096
[16:23:50] Freed 32 buffers of size 8192
[16:23:50] Freed 32 buffers of size 16384
[16:23:50] Freed 32 buffers of size 32768
[16:23:50] Freed 12 buffers of size 65536
[16:23:50] Freed 3 buffers of size 131072
[16:23:50] Freed 32 buffers of size 1048576
[16:23:50] Allocated 38142976 bytes of buffers on this pass and Freed 37093376 bytes, 1049600 bytes leaked
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

If you turn on Lanczos vs Bicubic OpenCL does that make any difference ?
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

s55 wrote:If you turn on Lanczos vs Bicubic OpenCL does that make any difference ?
OK, I've changed to Lanczos and am rerunning it now. I'll let you know the results. Interestingly, the "leaked bytes" is a lot smaller now (only 1K).

BTW, I've noticed that the text in the options seems to imply that Lanczos is the default. If that's true, I don't know how mine got changed to Bicube. I never messed with that option.

Thanks.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

There was a period when the setting was first added, that bicubic was default. The default change won't change the selection as it has no way of knowing if you chose that or it's an old default.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

s55 wrote:There was a period when the setting was first added, that bicubic was default. The default change won't change the selection as it has no way of knowing if you chose that or it's an old default.
Understood. I just wanted to make sure I wasn't doing something stupid. I'm not an A/V guy, and I try not to mess settings I don't understand.

Thanks again for your help.
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

No joy.

Code: Select all

HandBrake svn5912 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
Ram: 32708 MB, 
GPU Information:
  AMD Radeon HD 7900 Series - 9.12.0.0
Screen: 1920x1200
Temp Dir: C:\Users\Xenos\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Xenos\AppData\Roaming\HandBrake\HandBrake\0.0.0.5912

-------------------------------------------

CLI Query:  -i "D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1" -t 3 --angle 1 -c 1-8 -o "M:\TV2\A\Arrow (2012)\S1E01 - Pilot.mkv"  -f mkv  --detelecine --decomb -w 720 --crop 0:0:0:0 --loose-anamorphic  --modulus 2 -e x264 -q 20 --vfr -a 1,1 -E av_aac,copy:ac3 -6 dpl2,auto -R Auto,Auto -B 160,0 -D 0,0 --gain 0,0 --audio-fallback ac3 --subtitle 1 --markers="C:\Users\Xenos\AppData\Local\Temp\S1E01 - Pilot-3-chapters.csv" --h264-level="4.1"  --h264-profile=high  --verbose=2

[17:48:25] hb_init: starting libhb thread
[17:48:25] thread 3ee220 started ("libhb")
HandBrake svn5912 (2013120401) - MinGW x86_64 - http://handbrake.fr
8 CPUs detected
Opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1...
[17:48:25] CPU: Intel(R) Core(TM) i7-3820 CPU @ 3.60GHz
[17:48:25]  - Intel microarchitecture Sandy Bridge
[17:48:25]  - logical processor count: 8
[17:48:25] OpenCL device #1: Advanced Micro Devices, Inc. Tahiti
[17:48:25]  - OpenCL version: 1.2 AMD-APP (1084.4)
[17:48:25]  - driver version: 1084.4 (VM)
[17:48:25]  - device type:    GPU
[17:48:25]  - supported:      YES
[17:48:25] Intel Quick Sync Video support: no
[17:48:25] hb_scan: path=D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1, title_index=3
[17:48:25] thread 335010 started ("scan")
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/index.bdmv
libbluray/bdnav/index_parse.c:162: indx_parse(): error opening D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1/BDMV/BACKUP/index.bdmv
libbluray/bluray.c:1725: nav_get_title_list(D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1) failed (0000000000335770)
[17:48:25] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[17:48:25] scan: DVD has 13 title(s)
[17:48:25] scan: scanning title 3
[17:48:25] scan: opening IFO for VTS 1
[17:48:25] scan: duration is 00:42:33 (2553500 ms)
[17:48:25] pgc_id: 3, pgn: 1: pgc: 000000000033AED0
[17:48:25] scan: vts=1, ttn=3, cells=0->7, blocks=0->739343, 739344 blocks
[17:48:25] scan: checking audio 1
[17:48:25] scan: id=0x80bd, lang=English (AC3), 3cc=eng ext=0
[17:48:25] scan: checking audio 2
[17:48:25] scan: id=0x81bd, lang=Portugues (AC3), 3cc=por ext=0
[17:48:25] scan: checking audio 3
[17:48:25] scan: audio channel is not active
[17:48:25] scan: checking subtitle 1
[17:48:25] scan: id=0x21bd, lang=English, 3cc=eng ext=1
[17:48:25] scan: checking subtitle 2
[17:48:25] scan: id=0x23bd, lang=Chinese, 3cc=zho ext=0
[17:48:25] scan: checking subtitle 3
[17:48:25] scan: id=0x25bd, lang=Francais, 3cc=fra ext=0
[17:48:25] scan: checking subtitle 4
[17:48:25] scan: id=0x27bd, lang=Korean, 3cc=kor ext=0
[17:48:25] scan: checking subtitle 5
[17:48:25] scan: id=0x29bd, lang=Portugues, 3cc=por ext=0
[17:48:25] scan: checking subtitle 6
[17:48:25] scan: id=0x2bbd, lang=Espanol, 3cc=spa ext=0
[17:48:25] scan: checking subtitle 7
[17:48:25] scan: id=0x2dbd, lang=Thai, 3cc=tha ext=0
[17:48:25] scan: title 3 has 8 chapters
[17:48:25] scan: chap 1 c=0->0, b=0->43080 (43081), 115166 ms
[17:48:25] scan: chap 2 c=1->1, b=43081->196771 (153691), 521600 ms
[17:48:25] scan: chap 3 c=2->2, b=196772->328548 (131777), 458333 ms
[17:48:25] scan: chap 4 c=3->3, b=328549->452102 (123554), 442000 ms
[17:48:25] scan: chap 5 c=4->4, b=452103->620884 (168782), 577667 ms
[17:48:25] scan: chap 6 c=5->5, b=620885->735754 (114870), 409000 ms
[17:48:25] scan: chap 7 c=6->6, b=735755->739293 (3539), 28734 ms
[17:48:25] scan: chap 8 c=7->7, b=739294->739343 (50), 1000 ms
[17:48:25] scan: aspect = 1.77778
[17:48:25] scan: decoding previews for title 3
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[17:48:25] scan: title angle(s) 1
[17:48:25] scan: preview 1
[17:48:25] fifo_close: trashing 0 buffer(s)
[17:48:25] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Portugues (AC3) (Dolby Surround)
[17:48:25] fifo_close: trashing 0 buffer(s)
[17:48:25] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[17:48:25] scan: preview 2
[17:48:25] scan: preview 3
[17:48:25] scan: preview 4
[17:48:25] scan: preview 5
[17:48:25] scan: preview 6
[17:48:25] scan: preview 7
[17:48:25] scan: preview 8
[17:48:25] scan: preview 9
[17:48:25] scan: preview 10
[17:48:25] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[17:48:25] Freed 1 buffers of size 2048
[17:48:25] Freed 1 buffers of size 4096
[17:48:25] Freed 1 buffers of size 1048576
[17:48:25] Allocated 1054720 bytes of buffers on this pass and Freed 1054720 bytes, 0 bytes leaked
[17:48:25] thread 335010 exited ("scan")
[17:48:25] thread 335010 joined ("scan")
[17:48:25] libhb: scan thread found 1 valid title(s)
+ title 3:
  + vts 1, ttn 3, cells 0->7 (739344 blocks)
  + duration: 00:42:33
  + size: 720x480, pixel aspect: 32/27, display aspect: 1.78, 23.976 fps
  + autocrop: 0/0/0/0
  + support opencl: yes
  + support hwd: no
  + chapters:
    + 1: cells 0->0, 43081 blocks, duration 00:01:55
    + 2: cells 1->1, 153691 blocks, duration 00:08:42
    + 3: cells 2->2, 131777 blocks, duration 00:07:38
    + 4: cells 3->3, 123554 blocks, duration 00:07:22
    + 5: cells 4->4, 168782 blocks, duration 00:09:38
    + 6: cells 5->5, 114870 blocks, duration 00:06:49
    + 7: cells 6->6, 3539 blocks, duration 00:00:29
    + 8: cells 7->7, 50 blocks, duration 00:00:01
  + audio tracks:
    + 1, English (AC3) (5.1 ch) (iso639-2: eng), 48000Hz, 384000bps
    + 2, Portugues (AC3) (Dolby Surround) (iso639-2: por), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)(VOBSUB)
    + 2, Chinese (iso639-2: zho) (Bitmap)(VOBSUB)
    + 3, Francais (iso639-2: fra) (Bitmap)(VOBSUB)
    + 4, Korean (iso639-2: kor) (Bitmap)(VOBSUB)
    + 5, Portugues (iso639-2: por) (Bitmap)(VOBSUB)
    + 6, Espanol (iso639-2: spa) (Bitmap)(VOBSUB)
    + 7, Thai (iso639-2: tha) (Bitmap)(VOBSUB)
Reading chapter markers from file C:\Users\Xenos\AppData\Local\Temp\S1E01 - Pilot-3-chapters.csv
[17:48:25] thread 335010 started ("work")
[17:48:25] 1 job(s) to process
[17:48:25] starting job
[17:48:25] thread 6105a90 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 0
[17:48:25] thread 6355030 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 1
[17:48:25] thread 63554b0 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 2
[17:48:25] thread 6355930 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 3
[17:48:25] thread 6355db0 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 4
[17:48:25] thread 6355ad0 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 5
[17:48:25] thread 3bdfd0 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 6
[17:48:25] thread 3be450 started ("yadif_filter_segment")
[17:48:25] yadif thread started for segment 7
[17:48:25] thread 361120 started ("decomb_filter_segment")
[17:48:25] thread 3be5f0 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 0
[17:48:25] thread 361b60 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 1
[17:48:25] decomb filter thread started for segment 2
[17:48:25] thread 3623e0 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 3
[17:48:25] thread 3958e0 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 4
[17:48:25] thread 362580 started ("decomb_filter_segment")
[17:48:25] thread 395ab0 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 5
[17:48:25] thread 395c50 started ("decomb_filter_segment")
[17:48:25] decomb filter thread started for segment 6
[17:48:25] decomb filter thread started for segment 7
[17:48:25] thread 395df0 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 0
[17:48:25] thread 395f90 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 1
[17:48:25] thread 396130 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 2
[17:48:25] thread 3962d0 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 3
[17:48:25] thread 396470 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 4
[17:48:25] thread 396610 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 5
[17:48:25] thread 3967b0 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 6
[17:48:25] thread 396950 started ("decomb_check_segment")
[17:48:25] decomb check thread started for segment 7
[17:48:25] thread 396af0 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 0
[17:48:25] thread 396c90 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 1
[17:48:25] thread 396e30 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 2
[17:48:25] thread 396fd0 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 3
[17:48:25] thread 397170 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 4
[17:48:25] thread 397310 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 5
[17:48:25] thread 3974b0 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 6
[17:48:25] thread 397650 started ("mask_filter_segment")
[17:48:25] mask filter thread started for segment 7
[17:48:25] thread 3977f0 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 0
[17:48:25] thread 610df40 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 1
[17:48:25] thread 610e0e0 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 2
[17:48:25] thread 610e280 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 3
[17:48:25] thread 610e420 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 4
[17:48:25] thread 610e5c0 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 5
[17:48:25] thread 610e760 started ("mask_erode_segment")
[17:48:25] thread 610e900 started ("mask_erode_segment")
[17:48:25] mask erode thread started for segment 6
[17:48:25] mask erode thread started for segment 7
[17:48:25] thread 610eaa0 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 0
[17:48:25] thread 610ec40 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 1
[17:48:25] thread 610ede0 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 2
[17:48:25] thread 610ef80 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 3
[17:48:25] thread 610f120 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 4
[17:48:25] thread 610f2c0 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 5
[17:48:25] thread 610f460 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 6
[17:48:25] thread 610f600 started ("mask_dilate_segment")
[17:48:25] mask dilate thread started for segment 7
[17:48:25] sync: expecting 61222 video frames
[17:48:25] job configuration:
[17:48:25]  * source
[17:48:25]    + D:\DVD\arrow\S1D1\ARROW_SEASON_1_DISC_1
[17:48:25]    + title 3, chapter(s) 1 to 8
[17:48:25]  * destination
[17:48:25]    + M:\TV2\A\Arrow (2012)\S1E01 - Pilot.mkv
[17:48:25]    + container: Matroska (avformat)
[17:48:25]      + chapter markers
[17:48:25]  * video track
[17:48:25]    + decoder: mpeg2
[17:48:25]      + bitrate 7500 kbps
[17:48:25]    + filters
[17:48:25]      + Detelecine (pullup) (default settings)
[17:48:25]      + Decomb (default settings)
[17:48:25]      + Framerate Shaper (0:27000000:1126125)
[17:48:25]        + frame rate: same as source (around 23.976 fps)
[17:48:25]      + Crop and Scale (720:480:0:0:0:0)
[17:48:25]        + source: 720 * 480, crop (0/0/0/0): 720 * 480, scale: 720 * 480
[17:48:25]    + loose anamorphic
[17:48:25]      + storage dimensions: 720 * 480, mod 2
[17:48:25]      + pixel aspect ratio: 32 / 27
[17:48:25]      + display dimensions: 853 * 480
[17:48:25]    + encoder: H.264 (x264)
[17:48:25]      + h264 profile: high
[17:48:25]      + h264 level: 4.1
[17:48:25]      + quality: 20.00 (RF)
[17:48:25]  * subtitle track 1, English (track 1, id 0x21bd) Picture [VOBSUB] -> Passthrough
[17:48:25]  * audio track 1
[17:48:25]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[17:48:25]      + bitrate: 384 kbps, samplerate: 48000 Hz
[17:48:25]    + mixdown: Dolby Pro Logic II
[17:48:25]    + encoder: AAC (avcodec)
[17:48:25]      + bitrate: 160 kbps, samplerate: 48000 Hz
[17:48:25]  * audio track 2
[17:48:25]    + decoder: English (AC3) (5.1 ch) (track 1, id 0x80bd)
[17:48:25]      + bitrate: 384 kbps, samplerate: 48000 Hz
[17:48:25]    + AC3 Passthru
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
NAME OPEN FAILED
libdvdnav: Unable to find home directorylibdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Device (null) inaccessible, CSS authentication not available.
[17:48:25] thread 610f940 started ("Reader")
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions:[17:48:25] thread 610fae0 started ("Detelecine (pullup)")
 1 2 3 4 5 6 7[17:48:25] thread 610fc80 started ("Decomb")
 8
[17:48:25] thread 616ff70 started ("Framerate Shaper")
[17:48:25] thread 6170110 started ("Crop and Scale")
[17:48:25] thread 61702b0 started ("AudioSynchronization")
[17:48:25] thread 6170450 started ("AudioSynchronization")
[17:48:25] thread 61705f0 started ("MPEG-2 decoder (libmpeg2)")
[17:48:25] thread 6170790 started ("VOBSUB decoder")
[17:48:25] encx264: min-keyint: 24, keyint: 240
[17:48:25] encx264: encoding at constant RF 20.000000
[17:48:25] encx264: unparsed options: level=4.1:vbv-bufsize=78125:vbv-maxrate=62500
[17:48:25] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=32/27
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
[17:48:25] reader: first SCR 146 id 0xe0 DTS 1092
[17:48:25] mpeg2: "Chapter 1" (1) at frame 0 time 3003
x264 [info]: profile High, level 4.1
[17:48:25] thread 62fdbe0 started ("H.264/AVC encoder (libx264)")
[17:48:25] thread 62fdd80 started ("AC3 decoder")
[17:48:25] thread 62fdf20 started ("AVCodec Audio encoder (libavcodec)")
[17:48:25] thread 62fe0c0 started ("AC3 decoder")
[17:48:25] thread 62fe260 started ("Video Synchronization")
[17:48:25] sync: first pts is 3003
[17:48:25] Writing Metadata to output file...
[17:48:25] thread 62fe400 started ("Muxer")
[17:48:25] thread 62fe5a0 started ("Muxer")
[17:48:25] thread 62fe740 started ("Muxer")
[17:48:59] mpeg2: "Chapter 2" (2) at frame 2764 time 10378368
[17:51:15] mpeg2: "Chapter 3" (3) at frame 15283 time 57369312
[17:53:14] mpeg2: "Chapter 4" (4) at frame 26284 time 98660562
[17:55:06] mpeg2: "Chapter 5" (5) at frame 36893 time 138480342
[17:57:43] mpeg2: "Chapter 6" (6) at frame 50758 time 190522332
[17:59:27] mpeg2: "Chapter 7" (7) at frame 60575 time 227369142
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
libdvdnav: Language 'en' not found, using 'ÿÿ' instead
libdvdnav: Menu Languages available: ÿÿ 
[17:59:30] reader: done. 1 scr changes
[17:59:30] thread 610f940 exited ("Reader")
[17:59:30] mpeg2: "Chapter 8" (8) at frame 61265 time 229957728
[17:59:31] work: average encoding speed for job is 92.059998 fps
[17:59:31] thread 62fe260 exited ("Video Synchronization")
[17:59:31] thread 62fe740 exited ("Muxer")
[17:59:31] thread 61705f0 exited ("MPEG-2 decoder (libmpeg2)")
[17:59:31] thread 62fe260 joined ("Video Synchronization")
[17:59:31] sync: got 61287 frames, 61222 expected
[17:59:31] thread 610fae0 exited ("Detelecine (pullup)")
[17:59:31] thread 610fae0 joined ("Detelecine (pullup)")
[17:59:31] thread 610fc80 exited ("Decomb")
[17:59:31] thread 610fc80 joined ("Decomb")
[17:59:31] decomb: deinterlaced 1 | blended 22 | unfiltered 61260 | total 61283
[17:59:31] thread 6355930 exited ("yadif_filter_segment")
[17:59:31] thread 6355ad0 exited ("yadif_filter_segment")
[17:59:31] thread 63554b0 exited ("yadif_filter_segment")
[17:59:31] thread 6105a90 exited ("yadif_filter_segment")
[17:59:31] thread 3bdfd0 exited ("yadif_filter_segment")
[17:59:31] thread 6355db0 exited ("yadif_filter_segment")
[17:59:31] thread 3be450 exited ("yadif_filter_segment")
[17:59:31] thread 6355030 exited ("yadif_filter_segment")
[17:59:31] thread 6105a90 joined ("yadif_filter_segment")
[17:59:31] thread 6355030 joined ("yadif_filter_segment")
[17:59:31] thread 63554b0 joined ("yadif_filter_segment")
[17:59:31] thread 6355930 joined ("yadif_filter_segment")
[17:59:31] thread 6355db0 joined ("yadif_filter_segment")
[17:59:31] thread 6355ad0 joined ("yadif_filter_segment")
[17:59:31] thread 3bdfd0 joined ("yadif_filter_segment")
[17:59:31] thread 3be450 joined ("yadif_filter_segment")
[17:59:31] thread 3623e0 exited ("decomb_filter_segment")
[17:59:31] thread 3958e0 exited ("decomb_filter_segment")
[17:59:31] thread 3be5f0 exited ("decomb_filter_segment")
[17:59:31] thread 361120 exited ("decomb_filter_segment")
[17:59:31] thread 395c50 exited ("decomb_filter_segment")
[17:59:31] thread 395ab0 exited ("decomb_filter_segment")
[17:59:31] thread 361b60 exited ("decomb_filter_segment")
[17:59:31] thread 362580 exited ("decomb_filter_segment")
[17:59:31] thread 361120 joined ("decomb_filter_segment")
[17:59:31] thread 3be5f0 joined ("decomb_filter_segment")
[17:59:31] thread 361b60 joined ("decomb_filter_segment")
[17:59:31] thread 3623e0 joined ("decomb_filter_segment")
[17:59:31] thread 3958e0 joined ("decomb_filter_segment")
[17:59:31] thread 362580 joined ("decomb_filter_segment")
[17:59:31] thread 395ab0 joined ("decomb_filter_segment")
[17:59:31] thread 395c50 joined ("decomb_filter_segment")
[17:59:31] thread 396950 exited ("decomb_check_segment")
[17:59:31] thread 396610 exited ("decomb_check_segment")
[17:59:31] thread 396130 exited ("decomb_check_segment")
[17:59:31] thread 395f90 exited ("decomb_check_segment")
[17:59:31] thread 396470 exited ("decomb_check_segment")
[17:59:31] thread 395df0 exited ("decomb_check_segment")
[17:59:31] thread 3967b0 exited ("decomb_check_segment")
[17:59:31] thread 3962d0 exited ("decomb_check_segment")
[17:59:31] thread 395df0 joined ("decomb_check_segment")
[17:59:31] thread 395f90 joined ("decomb_check_segment")
[17:59:31] thread 396130 joined ("decomb_check_segment")
[17:59:31] thread 3962d0 joined ("decomb_check_segment")
[17:59:31] thread 396470 joined ("decomb_check_segment")
[17:59:31] thread 396610 joined ("decomb_check_segment")
[17:59:31] thread 3967b0 joined ("decomb_check_segment")
[17:59:31] thread 396950 joined ("decomb_check_segment")
[17:59:31] thread 396fd0 exited ("mask_filter_segment")
[17:59:31] thread 3974b0 exited ("mask_filter_segment")
[17:59:31] thread 397310 exited ("mask_filter_segment")
[17:59:31] thread 396af0 exited ("mask_filter_segment")
[17:59:31] thread 396e30 exited ("mask_filter_segment")
[17:59:31] thread 397170 exited ("mask_filter_segment")
[17:59:31] thread 396c90 exited ("mask_filter_segment")
[17:59:31] thread 396af0 joined ("mask_filter_segment")
[17:59:31] thread 397650 exited ("mask_filter_segment")
[17:59:31] thread 396c90 joined ("mask_filter_segment")
[17:59:31] thread 396e30 joined ("mask_filter_segment")
[17:59:31] thread 396fd0 joined ("mask_filter_segment")
[17:59:31] thread 397170 joined ("mask_filter_segment")
[17:59:31] thread 397310 joined ("mask_filter_segment")
[17:59:31] thread 3974b0 joined ("mask_filter_segment")
[17:59:31] thread 397650 joined ("mask_filter_segment")
[17:59:31] thread 610df40 exited ("mask_erode_segment")
[17:59:31] thread 610e420 exited ("mask_erode_segment")
[17:59:31] thread 610e280 exited ("mask_erode_segment")
[17:59:31] thread 610e760 exited ("mask_erode_segment")
[17:59:31] thread 610e0e0 exited ("mask_erode_segment")
[17:59:31] thread 610e900 exited ("mask_erode_segment")
[17:59:31] thread 3977f0 exited ("mask_erode_segment")
[17:59:31] thread 610e5c0 exited ("mask_erode_segment")
[17:59:31] thread 3977f0 joined ("mask_erode_segment")
[17:59:31] thread 610df40 joined ("mask_erode_segment")
[17:59:31] thread 610e0e0 joined ("mask_erode_segment")
[17:59:31] thread 610e280 joined ("mask_erode_segment")
[17:59:31] thread 610e420 joined ("mask_erode_segment")
[17:59:31] thread 610e5c0 joined ("mask_erode_segment")
[17:59:31] thread 610e760 joined ("mask_erode_segment")
[17:59:31] thread 610e900 joined ("mask_erode_segment")
[17:59:31] thread 610f2c0 exited ("mask_dilate_segment")
[17:59:31] thread 610f600 exited ("mask_dilate_segment")
[17:59:31] thread 610ede0 exited ("mask_dilate_segment")
[17:59:31] thread 610eaa0 exited ("mask_dilate_segment")
[17:59:31] thread 610f120 exited ("mask_dilate_segment")
[17:59:31] thread 610ec40 exited ("mask_dilate_segment")
[17:59:31] thread 610f460 exited ("mask_dilate_segment")
[17:59:31] thread 610ef80 exited ("mask_dilate_segment")
[17:59:31] thread 610eaa0 joined ("mask_dilate_segment")
[17:59:31] thread 610ec40 joined ("mask_dilate_segment")
[17:59:31] thread 610ede0 joined ("mask_dilate_segment")
[17:59:31] thread 610ef80 joined ("mask_dilate_segment")
[17:59:31] thread 610f120 joined ("mask_dilate_segment")
[17:59:31] thread 610f2c0 joined ("mask_dilate_segment")
[17:59:31] thread 610f460 joined ("mask_dilate_segment")
[17:59:31] thread 610f600 joined ("mask_dilate_segment")
[17:59:31] thread 616ff70 exited ("Framerate Shaper")
[17:59:31] thread 616ff70 joined ("Framerate Shaper")
[17:59:31] render: lost time: 9015 (0 frames)
[17:59:31] render: gained time: 9015 (12 frames) (0 not accounted for)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] thread 6170450 exited ("AudioSynchronization")
[17:59:31] thread 6170790 exited ("VOBSUB decoder")
[17:59:31] thread 62fe0c0 exited ("AC3 decoder")
[17:59:31] thread 62fe5a0 exited ("Muxer")
[17:59:31] thread 62fdf20 exited ("AVCodec Audio encoder (libavcodec)")
[17:59:31] thread 61702b0 exited ("AudioSynchronization")
[17:59:31] thread 62fdd80 exited ("AC3 decoder")
[17:59:31] thread 62fe400 exited ("Muxer")
[17:59:31] thread 6170110 exited ("Crop and Scale")
[17:59:31] thread 6170110 joined ("Crop and Scale")
[17:59:31] thread 61702b0 joined ("AudioSynchronization")
[17:59:31] thread 6170450 joined ("AudioSynchronization")
[17:59:31] thread 61705f0 joined ("MPEG-2 decoder (libmpeg2)")
[17:59:31] mpeg2 done: 61287 frames
[17:59:31] thread 6170790 joined ("VOBSUB decoder")
[17:59:31] thread 62fdbe0 exited ("H.264/AVC encoder (libx264)")
[17:59:31] thread 62fdbe0 joined ("H.264/AVC encoder (libx264)")
x264 [info]: frame I:1224  Avg QP:17.14  size: 31869
x264 [info]: frame P:30087 Avg QP:20.63  size: 10695
x264 [info]: frame B:29972 Avg QP:22.25  size:  2622
x264 [info]: consecutive B-frames: 22.5% 31.8% 15.7% 30.1%
x264 [info]: mb I  I16..4:  5.9% 89.7%  4.3%
x264 [info]: mb P  I16..4:  1.7% 11.2%  0.8%  P16..4: 46.9% 17.9% 10.2%  0.0%  0.0%    skip:11.1%
x264 [info]: mb B  I16..4:  0.1%  0.5%  0.1%  B16..8: 43.2%  4.1%  0.8%  direct: 2.8%  skip:48.6%  L0:40.7% L1:48.5% BI:10.8%
x264 [info]: 8x8 transform intra:83.1% inter:82.9%
x264 [info]: coded y,uvDC,uvAC intra: 74.9% 80.4% 41.2% inter: 25.2% 34.9% 2.6%
x264 [info]: i16 v,h,dc,p: 31% 16% 10% 44%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu: 24% 15% 25%  4%  6%  7%  6%  7%  6%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 28% 14% 14%  6%  9% 10%  7%  7%  4%
x264 [info]: i8c dc,h,v,p: 50% 17% 25%  8%
x264 [info]: Weighted P-Frames: Y:6.3% UV:3.6%
x264 [info]: ref P L0: 62.0% 14.3% 16.7%  6.6%  0.4%
x264 [info]: ref B L0: 86.4% 12.1%  1.5%
x264 [info]: ref B L1: 95.9%  4.1%
x264 [info]: kb/s:1375.22
[17:59:31] thread 62fdd80 joined ("AC3 decoder")
[17:59:31] thread 62fdf20 joined ("AVCodec Audio encoder (libavcodec)")
[17:59:31] encavcodeca: closing libavcodec
[17:59:31] thread 62fe0c0 joined ("AC3 decoder")
[17:59:31] thread 62fe400 joined ("Muxer")
[17:59:31] thread 62fe5a0 joined ("Muxer")
[17:59:31] thread 62fe740 joined ("Muxer")
[17:59:31] mux: file size, 615696285 bytes
[17:59:31] mux: track 0, 61283 frames, 439326102 bytes, 1375.04 kbps, fifo 2048
[17:59:31] mux: track 1, 119813 frames, 50940341 bytes, 159.44 kbps, fifo 4096
[17:59:31] mux: track 2, 79876 frames, 122689536 bytes, 384.00 kbps, fifo 4096
[17:59:31] mux: track 3, 494 frames, 866720 bytes, 2.71 kbps, fifo 32
[17:59:31] mux: overhead, 7.17 bytes per frame
[17:59:31] thread 610f940 joined ("Reader")
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] fifo_close: trashing 0 buffer(s)
[17:59:31] Freed 320 buffers of size 1024
[17:59:31] Freed 32 buffers of size 2048
[17:59:31] Freed 32 buffers of size 4096
[17:59:31] Freed 32 buffers of size 8192
[17:59:31] Freed 32 buffers of size 16384
[17:59:31] Freed 32 buffers of size 32768
[17:59:31] Freed 32 buffers of size 65536
[17:59:31] Freed 7 buffers of size 131072
[17:59:31] Freed 32 buffers of size 1048576
[17:59:31] Allocated 38928384 bytes of buffers on this pass and Freed 38928384 bytes, 0 bytes leaked
dmatter
Posts: 1
Joined: Tue Dec 10, 2013 9:15 pm

Re: CLI Crashes

Post by dmatter »

hello with the svn 5912 y have the same crash qith handbrakeCLI
with the same error code in windows event log

Code: Select all

Nom de l’application défaillante HandBrakeCLI.exe, version : 0.0.0.0, horodatage : 0x529ea5f7
Nom du module défaillant : HandBrakeCLI.exe, version : 0.0.0.0, horodatage : 0x529ea5f7
Code d’exception : 0xc0000005
Décalage d’erreur : 0x00000000009c50dc
ID du processus défaillant : 0xc2c
Heure de début de l’application défaillante : 0x01cef5eae0fcac87
Chemin d’accès de l’application défaillante : C:\Program Files\Handbrake\HandBrakeCLI.exe
Chemin d’accès du module défaillant: C:\Program Files\Handbrake\HandBrakeCLI.exe
ID de rapport : 7f8bb255-61df-11e3-b707-00221531d9df
Still I have this error on the same file

is the beginning of all the 2nd pass (0.11%) or
at the end of the 2nd pass (100%)
what's weird is that I had this error on some file I can convert the following day except that I was 3, which are impossible to convert it all comes from the same DVD
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

Think I may have tracked it down tonight so hopefully a fix coming for that soon.
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

Hoping https://trac.handbrake.fr/changeset/5927 will fix it. Don't have time to test tonight so I guess we'll find out tomorrow.
Deleted User 11865

Re: CLI Crashes

Post by Deleted User 11865 »

s55 wrote:Hoping https://trac.handbrake.fr/changeset/5927 will fix it. Don't have time to test tonight so I guess we'll find out tomorrow.
My bad. But you could have read the comment ;-) :P
User avatar
s55
HandBrake Team
Posts: 10350
Joined: Sun Dec 24, 2006 1:05 pm

Re: CLI Crashes

Post by s55 »

Meh, Silly.

Fixed
DrXenos
Bright Spark User
Posts: 278
Joined: Sat Mar 16, 2013 1:19 pm

Re: CLI Crashes

Post by DrXenos »

Thanks, guys. I'll give it a whirl when I get a chance. I really appreciate your effects.
Post Reply