Video Encoding Hangs - Certain Movies

Support for HandBrake on Linux, Solaris, and other Unix-like platforms
Forum rules
An Activity Log is required for support requests. Please read How-to get an activity log? for details on how and why this should be provided.
Post Reply
Gamzarme
Posts: 9
Joined: Thu Dec 03, 2009 7:18 pm

Video Encoding Hangs - Certain Movies

Post by Gamzarme » Thu Dec 03, 2009 7:40 pm

Hi. This is my first post to the Handbrake forum, though I've been a lurker for the past year or so. Great information on here and I've been following and highly anticipating the release of 0.9.4.

I've been working on encoding many of my movies to h.264 and have run into a few problematic titles that do not seem to progress further than a certain point. They do, however, play past and allow for seeking beyond the part where it locks up on the encoding. I've used the same method for each title. Use AnyDVD (Windows) in conjunction with DVDShrink to create an ISO. Then, in Ubuntu, read in the ISO with Handbrake, set a custom profile based on "High Profile", modify the dimensions, enqueue and encode.

The logs are shown below. If anyone can shed some light on where the process is going wrong or where it's hanging, that'd be very helpful!

The first movie is "Alien (1979) (Special Edition)".

First try:

Code: Select all

Handbrake Version: svn2845 (2009092801)
[10:55:11] hb_scan: path=/home/server12/Video/Processing/Encode/Alien (1979).iso, title_index=1
[10:55:11] scan: trying to open with libdvdread
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[10:55:11] scan: DVD has 21 title(s)
[10:55:11] scan: scanning title 1
[10:55:11] scan: opening IFO for VTS 2
[10:55:11] pgc_id: 1, pgn: 1: pgc: 0xc4ae8c0
[10:55:11] scan: vts=2, ttn=1, cells=0->97, blocks=0->3810520, 3657724 blocks
[10:55:11] scan: duration is 01:55:42 (6942043 ms)
[10:55:11] scan: checking audio 1
[10:55:11] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[10:55:11] scan: checking audio 2
[10:55:11] scan: id=89bd, lang=English (DTS), 3cc=eng ext=1
[10:55:11] scan: checking audio 3
[10:55:11] scan: id=82bd, lang=Espanol (AC3), 3cc=spa ext=1
[10:55:11] scan: checking audio 4
[10:55:11] scan: id=83bd, lang=English (AC3), 3cc=eng ext=1
[10:55:11] scan: checking audio 5
[10:55:11] scan: id=84bd, lang=English (AC3), 3cc=eng ext=1
[10:55:11] scan: checking subtitle 1
[10:55:11] scan: id=21bd, lang=English (Closed Caption), 3cc=eng
[10:55:11] scan: checking subtitle 2
[10:55:11] scan: id=23bd, lang=Espanol, 3cc=spa
[10:55:11] scan: checking subtitle 3
[10:55:11] scan: id=25bd, lang=Espanol, 3cc=spa
[10:55:11] scan: checking subtitle 4
[10:55:11] scan: id=27bd, lang=Unknown, 3cc=und
[10:55:11] scan: title 1 has 41 chapters
[10:55:11] scan: chap 1 c=0->1, b=0->59973 (59974), 120905 ms
[10:55:11] scan: chap 2 c=2->4, b=59974->127025 (67052), 157932 ms
[10:55:11] scan: chap 3 c=5->7, b=127026->208326 (79524), 192437 ms
[10:55:11] scan: chap 4 c=8->9, b=208327->251913 (39176), 76719 ms
[10:55:11] scan: chap 5 c=10->11, b=251914->335942 (84029), 209348 ms
[10:55:11] scan: chap 6 c=12->12, b=335943->390005 (54063), 95465 ms
[10:55:11] scan: chap 7 c=13->14, b=390006->559572 (166159), 350979 ms
[10:55:11] scan: chap 8 c=15->15, b=559573->635547 (75975), 196928 ms
[10:55:11] scan: chap 9 c=16->18, b=635548->689774 (48636), 100829 ms
[10:55:11] scan: chap 10 c=19->21, b=689775->764594 (71767), 164224 ms
[10:55:11] scan: chap 11 c=22->25, b=764595->907276 (132013), 240985 ms
[10:55:11] scan: chap 12 c=26->30, b=907277->1022062 (106024), 220891 ms
[10:55:11] scan: chap 13 c=31->32, b=1022063->1060756 (38694), 91759 ms
[10:55:11] scan: chap 14 c=33->36, b=1060757->1160532 (91330), 209562 ms
[10:55:11] scan: chap 15 c=37->39, b=1160533->1237349 (76817), 171358 ms
[10:55:11] scan: chap 16 c=40->42, b=1237350->1348156 (106447), 241655 ms
[10:55:11] scan: chap 17 c=43->44, b=1348157->1415234 (67078), 158079 ms
[10:55:11] scan: chap 18 c=45->48, b=1415235->1473918 (46875), 96867 ms
[10:55:11] scan: chap 19 c=49->49, b=1473919->1538660 (64742), 161050 ms
[10:55:11] scan: chap 20 c=50->52, b=1538661->1677049 (131605), 276695 ms
[10:55:11] scan: chap 21 c=53->53, b=1677050->1712790 (35741), 85464 ms
[10:55:11] scan: chap 22 c=54->55, b=1712791->1851643 (132190), 189961 ms
[10:55:11] scan: chap 23 c=56->57, b=1851644->2030985 (179342), 282636 ms
[10:55:11] scan: chap 24 c=58->60, b=2030986->2111619 (73274), 105071 ms
[10:55:11] scan: chap 25 c=61->64, b=2111620->2237445 (110460), 167007 ms
[10:55:11] scan: chap 26 c=65->65, b=2237446->2292904 (55459), 101616 ms
[10:55:11] scan: chap 27 c=66->67, b=2292905->2382416 (89512), 161092 ms
[10:55:11] scan: chap 28 c=68->70, b=2382417->2451640 (62669), 107821 ms
[10:55:11] scan: chap 29 c=71->72, b=2451641->2567993 (109908), 211180 ms
[10:55:11] scan: chap 30 c=73->75, b=2567994->2739243 (163469), 244870 ms
[10:55:11] scan: chap 31 c=76->79, b=2739244->2834942 (80443), 129967 ms
[10:55:11] scan: chap 32 c=80->80, b=2834943->2901658 (66716), 118874 ms
[10:55:11] scan: chap 33 c=81->81, b=2901659->2961730 (60072), 110736 ms
[10:55:11] scan: chap 34 c=82->83, b=2961731->3019140 (57410), 103803 ms
[10:55:11] scan: chap 35 c=84->84, b=3019141->3155180 (136040), 202108 ms
[10:55:11] scan: chap 36 c=85->86, b=3155181->3209572 (50208), 88933 ms
[10:55:11] scan: chap 37 c=87->91, b=3209573->3417472 (193783), 253710 ms
[10:55:11] scan: chap 38 c=92->92, b=3417473->3500271 (82799), 170102 ms
[10:55:11] scan: chap 39 c=93->95, b=3500272->3730181 (229910), 407281 ms
[10:55:11] scan: chap 40 c=96->96, b=3730182->3810515 (80334), 164944 ms
[10:55:11] scan: chap 41 c=97->97, b=3810516->3810520 (5), 176 ms
[10:55:11] scan: aspect = 0
[10:55:11] scan: decoding previews for title 1

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 1
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 1
[10:55:12] scan: title angle(s) 1
[10:55:12] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[10:55:12] scan: audio 0x89bd: DCA, rate=48000Hz, bitrate=768000 English (DTS) (5.1 ch)
[10:55:12] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (2.0 ch)
[10:55:12] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[10:55:12] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[10:55:12] scan: content PAR gives wrong aspect 1.78; using container aspect 1.78
[10:55:12] scan: 10 previews, 720x480, 23.976 fps, autocrop = 54/58/0/2, aspect 1.78:1, PAR 853:720
[10:55:12] scan: title (0) job->width:720, job->height:304
[10:55:12] libhb: scan thread found 1 valid title(s)
[10:55:14] gtkgui: Modified Custom Preset: Ripping DVDs
[10:55:14] 1 job(s) to process
[10:55:14] starting job
[10:55:14] job configuration:
[10:55:14]  * source
[10:55:14]    + /home/server12/Video/Processing/Encode/Alien (1979).iso
[10:55:14]    + title 1, chapter(s) 1 to 40
[10:55:14]  * destination
[10:55:14]    + /home/server12/Video/Processing/Encoding/Alien (1979) (Special Edition).mkv
[10:55:14]    + container: Matroska (.mkv)
[10:55:14]      + chapter markers
[10:55:14]  * video track
[10:55:14]    + decoder: mpeg2
[10:55:14]      + bitrate 9800 kbps
[10:55:14]    + frame rate: same as source (around 23.976 fps)
[10:55:14]    + loose anamorphic
[10:55:14]      + modulus: 2
[10:55:14]      + storage dimensions: 720 * 480 -> 718 * 366, crop 55/59/0/2
[10:55:14]      + pixel aspect ratio: 425 / 359
[10:55:14]      + display dimensions: 850 * 366
[10:55:14]    + filters
[10:55:14]      + Detelecine (pullup) (default settings)
[10:55:14]      + Decomb (default settings)
[10:55:14]    + encoder: x264
[10:55:14]      + options: b-adapt=2:me=umh:subme=9:analyse=all:trellis=0
[10:55:14]      + quality: 20.00 (RF)
[10:55:14]  * subtitle track 1, English (Closed Caption) (id 21bd) Picture [VOBSUB] -> Pass-Through 
[10:55:14]  * audio track 0
[10:55:14]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[10:55:14]      + bitrate: 448 kbps, samplerate: 48000 Hz
[10:55:14]    + AC3 passthrough
libdvdread: Using libdvdcss version 1.2.10 for DVD access

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
[10:55:14] yadif thread started for segment 0
[10:55:14] yadif thread started for segment 1
[10:55:14] decomb thread started for segment 0
[10:55:14] decomb thread started for segment 1
[10:55:14] encx264: keyint-min: 24, keyint-max: 240
[10:55:14] encx264: encoding with stored aspect 425/359
[10:55:14] encx264: Encoding at constant RF 20.000000
x264 [warning]: width or height not divisible by 16 (718x366), compression will suffer.
x264 [info]: using SAR=425/359
x264 [info]: using cpu capabilities: MMX2 SSE2 SSE3 Cache64
[10:55:14] reader: first SCR 146 id 224 DTS 15798
[10:55:14] mpeg2: "Chapter  1" (1) at frame 0 time 3003
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
[10:55:14] sync: expecting 166462 video frames
[ac3 @ 0xa669b690]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[10:55:14] sync: first pts is 3003
[10:59:42] mpeg2: "Chapter  2" (2) at frame 2896 time 10873863
[11:06:40] mpeg2: "Chapter  3" (3) at frame 6692 time 25123462
[11:06:45] 281.351778s: Film -> Video
[11:06:46] 281.818911s: Video -> Film
[11:15:40] mpeg2: "Chapter  4" (4) at frame 11323 time 42496560
[11:16:05] 480.325467s: Film -> Video
[11:16:06] 480.825967s: Video -> Film
[11:19:07] mpeg2: "Chapter  5" (5) at frame 13182 time 49463919
[11:29:18] mpeg2: "Chapter  6" (6) at frame 18214 time 68350230
[11:33:25] mpeg2: "Chapter  7" (7) at frame 20510 time 76968840
[11:38:36] 971.158500s: Film -> Video
[11:38:38] 971.625633s: Video -> Film
[11:52:12] mpeg2: "Chapter  8" (8) at frame 28905 time 108470751
[12:02:01] mpeg2: "Chapter  9" (9) at frame 33617 time 126158421
[12:07:13] sync: adding 70 ms of silence to audio 0  start 133831267, next 133824960
[12:07:14] 1487.016378s: Film -> Video
[12:07:16] 1487.516878s: Video -> Film
[12:08:02] mpeg2: "Chapter 10" (10) at frame 36075 time 135373899
[12:08:20] 1511.482111s: Film -> Video
[12:08:22] 1511.965922s: Video -> Film
[12:17:10] mpeg2: "Chapter 11" (11) at frame 40048 time 150277303
[12:17:56] 1681.225944s: Film -> Video
[12:17:59] 1681.726444s: Video -> Film
Second Try:

Code: Select all

Handbrake Version: svn2845 (2009092801)
[13:17:19] hb_scan: path=/home/server12/Video/Processing/Encode/Alien (1979).iso, title_index=1
[13:17:19] scan: trying to open with libdvdread
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:17:19] scan: DVD has 21 title(s)
[13:17:19] scan: scanning title 1
[13:17:19] scan: opening IFO for VTS 2
[13:17:19] pgc_id: 1, pgn: 1: pgc: 0xab225c8
[13:17:19] scan: vts=2, ttn=1, cells=0->97, blocks=0->3810520, 3657724 blocks
[13:17:19] scan: duration is 01:55:42 (6942043 ms)
[13:17:19] scan: checking audio 1
[13:17:19] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:17:19] scan: checking audio 2
[13:17:19] scan: id=89bd, lang=English (DTS), 3cc=eng ext=1
[13:17:19] scan: checking audio 3
[13:17:19] scan: id=82bd, lang=Espanol (AC3), 3cc=spa ext=1
[13:17:19] scan: checking audio 4
[13:17:19] scan: id=83bd, lang=English (AC3), 3cc=eng ext=1
[13:17:19] scan: checking audio 5
[13:17:19] scan: id=84bd, lang=English (AC3), 3cc=eng ext=1
[13:17:19] scan: checking subtitle 1
[13:17:19] scan: id=21bd, lang=English (Closed Caption), 3cc=eng
[13:17:19] scan: checking subtitle 2
[13:17:19] scan: id=23bd, lang=Espanol, 3cc=spa
[13:17:19] scan: checking subtitle 3
[13:17:19] scan: id=25bd, lang=Espanol, 3cc=spa
[13:17:19] scan: checking subtitle 4
[13:17:19] scan: id=27bd, lang=Unknown, 3cc=und
[13:17:19] scan: title 1 has 41 chapters
[13:17:19] scan: chap 1 c=0->1, b=0->59973 (59974), 120905 ms
[13:17:19] scan: chap 2 c=2->4, b=59974->127025 (67052), 157932 ms
[13:17:19] scan: chap 3 c=5->7, b=127026->208326 (79524), 192437 ms
[13:17:19] scan: chap 4 c=8->9, b=208327->251913 (39176), 76719 ms
[13:17:19] scan: chap 5 c=10->11, b=251914->335942 (84029), 209348 ms
[13:17:19] scan: chap 6 c=12->12, b=335943->390005 (54063), 95465 ms
[13:17:19] scan: chap 7 c=13->14, b=390006->559572 (166159), 350979 ms
[13:17:19] scan: chap 8 c=15->15, b=559573->635547 (75975), 196928 ms
[13:17:19] scan: chap 9 c=16->18, b=635548->689774 (48636), 100829 ms
[13:17:19] scan: chap 10 c=19->21, b=689775->764594 (71767), 164224 ms
[13:17:19] scan: chap 11 c=22->25, b=764595->907276 (132013), 240985 ms
[13:17:19] scan: chap 12 c=26->30, b=907277->1022062 (106024), 220891 ms
[13:17:19] scan: chap 13 c=31->32, b=1022063->1060756 (38694), 91759 ms
[13:17:19] scan: chap 14 c=33->36, b=1060757->1160532 (91330), 209562 ms
[13:17:19] scan: chap 15 c=37->39, b=1160533->1237349 (76817), 171358 ms
[13:17:19] scan: chap 16 c=40->42, b=1237350->1348156 (106447), 241655 ms
[13:17:19] scan: chap 17 c=43->44, b=1348157->1415234 (67078), 158079 ms
[13:17:19] scan: chap 18 c=45->48, b=1415235->1473918 (46875), 96867 ms
[13:17:19] scan: chap 19 c=49->49, b=1473919->1538660 (64742), 161050 ms
[13:17:19] scan: chap 20 c=50->52, b=1538661->1677049 (131605), 276695 ms
[13:17:19] scan: chap 21 c=53->53, b=1677050->1712790 (35741), 85464 ms
[13:17:19] scan: chap 22 c=54->55, b=1712791->1851643 (132190), 189961 ms
[13:17:19] scan: chap 23 c=56->57, b=1851644->2030985 (179342), 282636 ms
[13:17:19] scan: chap 24 c=58->60, b=2030986->2111619 (73274), 105071 ms
[13:17:19] scan: chap 25 c=61->64, b=2111620->2237445 (110460), 167007 ms
[13:17:19] scan: chap 26 c=65->65, b=2237446->2292904 (55459), 101616 ms
[13:17:19] scan: chap 27 c=66->67, b=2292905->2382416 (89512), 161092 ms
[13:17:19] scan: chap 28 c=68->70, b=2382417->2451640 (62669), 107821 ms
[13:17:19] scan: chap 29 c=71->72, b=2451641->2567993 (109908), 211180 ms
[13:17:19] scan: chap 30 c=73->75, b=2567994->2739243 (163469), 244870 ms
[13:17:19] scan: chap 31 c=76->79, b=2739244->2834942 (80443), 129967 ms
[13:17:19] scan: chap 32 c=80->80, b=2834943->2901658 (66716), 118874 ms
[13:17:19] scan: chap 33 c=81->81, b=2901659->2961730 (60072), 110736 ms
[13:17:19] scan: chap 34 c=82->83, b=2961731->3019140 (57410), 103803 ms
[13:17:19] scan: chap 35 c=84->84, b=3019141->3155180 (136040), 202108 ms
[13:17:19] scan: chap 36 c=85->86, b=3155181->3209572 (50208), 88933 ms
[13:17:19] scan: chap 37 c=87->91, b=3209573->3417472 (193783), 253710 ms
[13:17:19] scan: chap 38 c=92->92, b=3417473->3500271 (82799), 170102 ms
[13:17:19] scan: chap 39 c=93->95, b=3500272->3730181 (229910), 407281 ms
[13:17:19] scan: chap 40 c=96->96, b=3730182->3810515 (80334), 164944 ms
[13:17:19] scan: chap 41 c=97->97, b=3810516->3810520 (5), 176 ms
[13:17:19] scan: aspect = 0
[13:17:19] scan: decoding previews for title 1

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
[13:17:19] scan: title angle(s) 1
[13:17:19] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:17:19] scan: audio 0x89bd: DCA, rate=48000Hz, bitrate=768000 English (DTS) (5.1 ch)
[13:17:19] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (2.0 ch)
[13:17:19] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[13:17:19] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[13:17:20] scan: content PAR gives wrong aspect 1.78; using container aspect 1.78
[13:17:20] scan: 10 previews, 720x480, 23.976 fps, autocrop = 54/58/0/2, aspect 1.78:1, PAR 853:720
[13:17:20] scan: title (0) job->width:720, job->height:304
[13:17:20] libhb: scan thread found 1 valid title(s)
[13:17:22] gtkgui: Modified Preset: Ripping DVDs
[13:17:22] 1 job(s) to process
[13:17:22] starting job
[13:17:22] job configuration:
[13:17:22]  * source
[13:17:22]    + /home/server12/Video/Processing/Encode/Alien (1979).iso
[13:17:22]    + title 1, chapter(s) 1 to 40
[13:17:22]  * destination
[13:17:22]    + /home/server12/Video/Processing/Encoding/Alien (1979) (Special Edition).mkv
[13:17:22]    + container: Matroska (.mkv)
[13:17:22]      + chapter markers
[13:17:22]  * video track
[13:17:22]    + decoder: mpeg2
[13:17:22]      + bitrate 9800 kbps
[13:17:22]    + frame rate: same as source (around 23.976 fps)
[13:17:22]    + loose anamorphic
[13:17:22]      + modulus: 2
[13:17:22]      + storage dimensions: 720 * 480 -> 718 * 366, crop 55/59/0/2
[13:17:22]      + pixel aspect ratio: 425 / 359
[13:17:22]      + display dimensions: 850 * 366
[13:17:22]    + filters
[13:17:22]      + Detelecine (pullup) (default settings)
[13:17:22]      + Decomb (default settings)
[13:17:22]    + encoder: x264
[13:17:22]      + options: b-adapt=2:me=umh:subme=9:analyse=all:trellis=0
[13:17:22]      + quality: 20.00 (RF)
[13:17:22]  * subtitle track 1, English (Closed Caption) (id 21bd) Picture [VOBSUB] -> Pass-Through 
[13:17:22]  * audio track 0
[13:17:22]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[13:17:22]      + bitrate: 448 kbps, samplerate: 48000 Hz
[13:17:22]    + AC3 passthrough
libdvdread: Using libdvdcss version 1.2.10 for DVD access

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
[13:17:22] yadif thread started for segment 0
[13:17:22] yadif thread started for segment 1
[13:17:22] decomb thread started for segment 0
[13:17:22] decomb thread started for segment 1
[13:17:22] encx264: keyint-min: 24, keyint-max: 240
[13:17:22] encx264: encoding with stored aspect 425/359
[13:17:22] encx264: Encoding at constant RF 20.000000
x264 [warning]: width or height not divisible by 16 (718x366), compression will suffer.
x264 [info]: using SAR=425/359
x264 [info]: using cpu capabilities: MMX2 SSE2 SSE3 Cache64
[13:17:22] reader: first SCR 146 id 224 DTS 15798
[13:17:22] mpeg2: "Chapter  1" (1) at frame 0 time 3003
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
[13:17:22] sync: expecting 166462 video frames
[ac3 @ 0xb562330]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[13:17:22] sync: first pts is 3003
[13:21:58] mpeg2: "Chapter  2" (2) at frame 2896 time 10873863
[13:28:55] mpeg2: "Chapter  3" (3) at frame 6692 time 25123462
[13:29:00] 281.351778s: Film -> Video
[13:29:02] 281.818911s: Video -> Film
[13:37:54] mpeg2: "Chapter  4" (4) at frame 11323 time 42496560
[13:38:19] 480.325467s: Film -> Video
[13:38:21] 480.825967s: Video -> Film
[13:41:21] mpeg2: "Chapter  5" (5) at frame 13182 time 49463919
[13:51:32] mpeg2: "Chapter  6" (6) at frame 18214 time 68350230
[13:55:38] mpeg2: "Chapter  7" (7) at frame 20510 time 76968840
[14:00:50] 971.158500s: Film -> Video
[14:00:52] 971.625633s: Video -> Film
[14:14:25] mpeg2: "Chapter  8" (8) at frame 28905 time 108470751
[14:24:15] mpeg2: "Chapter  9" (9) at frame 33617 time 126158421
[14:29:26] sync: adding 70 ms of silence to audio 0  start 133831267, next 133824960
[14:29:28] 1487.016378s: Film -> Video
[14:29:30] 1487.516878s: Video -> Film
[14:30:16] mpeg2: "Chapter 10" (10) at frame 36075 time 135373899
[14:30:34] 1511.482111s: Film -> Video
[14:30:36] 1511.965922s: Video -> Film
[14:39:24] mpeg2: "Chapter 11" (11) at frame 40048 time 150277303
[14:40:11] 1681.225944s: Film -> Video
[14:40:13] 1681.726444s: Video -> Film
[15:26:07] sync: got 41256 frames, 166462 expected
[15:26:07] work: average encoding speed for job is 8.105404 fps
[15:26:07] reader: done. 12 scr changes
[15:26:07] mux: track 0, 41183 frames, 169206366 bytes, 788.00 kbps, fifo 8
[15:26:07] mux: track 1, 53682 frames, 96198144 bytes, 448.00 kbps, fifo 256
[15:26:07] mux: track 2, 238 frames, 425694 bytes, 1.98 kbps, fifo 8
[15:26:07] mpeg2 done: 41257 frames
[15:26:07] render: lost time: 60060 (20 frames)
[15:26:07] render: gained time: 60060 (78 frames) (0 not accounted for)
[15:26:07] render: average dropped frame duration: 3003
x264 [info]: frame I:506   Avg QP:18.24  size: 31366  PSNR Mean Y:45.80 U:48.48 V:49.26 Avg:46.58 Global:45.74
x264 [info]: frame P:14201 Avg QP:20.24  size:  7176  PSNR Mean Y:44.48 U:47.73 V:48.50 Avg:45.26 Global:44.32
x264 [info]: frame B:26477 Avg QP:23.52  size:  1943  PSNR Mean Y:43.10 U:47.09 V:47.91 Avg:44.10 Global:43.49
x264 [info]: consecutive B-frames:  1.7%  9.0% 77.0% 12.4%
x264 [info]: mb I  I16..4:  8.1% 77.7% 14.2%
x264 [info]: mb P  I16..4:  1.1%  4.5%  0.8%  P16..4: 45.5% 14.3% 13.9%  0.4%  0.4%    skip:19.1%
x264 [info]: mb B  I16..4:  0.1%  0.8%  0.1%  B16..8: 44.1%  1.0%  1.5%  direct: 3.4%  skip:48.9%  L0:38.9% L1:54.3% BI: 6.7%
x264 [info]: 8x8 transform intra:73.5% inter:60.1%
x264 [info]: coded y,uvDC,uvAC intra: 81.2% 79.4% 52.4% inter: 12.9% 15.4% 2.1%
x264 [info]: i16 v,h,dc,p: 39% 17%  8% 35%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu:  7%  7%  4% 11% 16% 15% 15% 12% 13%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 10%  7%  3% 11% 15% 16% 13% 13% 13%
x264 [info]: ref P L0: 73.5% 15.3% 11.2%
x264 [info]: ref B L0: 82.6% 17.4%
x264 [info]: SSIM Mean Y:0.9779029
x264 [info]: PSNR Mean Y:43.611 U:47.330 V:48.132 Avg:44.528 Global:43.781 kb/s:788.15
[15:26:07] decomb: deinterlaced 0 | blended 4 | unfiltered 41231 | total 41235
[15:26:07] libhb: work result = 0
Thanks so much for checking the log. I've looked and cannot find where it went wrong, it seems as if everything is all right until I have to cancel and hung process. This happens every time I attempt the video encode.

Then again, maybe I need to update to the final release of 0.9.4 which was out one or two SVN releases later. Is this a bug that has been recently patched?

User avatar
JohnAStebbins
HandBrake Team
Posts: 5408
Joined: Sat Feb 09, 2008 7:21 pm

Re: Video Encoding Hangs - Certain Movies

Post by JohnAStebbins » Thu Dec 03, 2009 9:43 pm

I don't recall any deadlock bugs in that particular version, but it would be good to know if you can reproduce it in 0.9.4.

Gamzarme
Posts: 9
Joined: Thu Dec 03, 2009 7:18 pm

Re: Video Encoding Hangs - Certain Movies

Post by Gamzarme » Sat Dec 05, 2009 3:03 am

All right, here's the newest log from Alien. This time I'm using svn2965 (0.9.4), still I get the hang at the same percentage: 24.78%. Any thoughts as to why this is happening? Do I need to run the encode again with "2" Activity Log Verbosity Level?

Code: Select all

Handbrake Version: rev2965 (2009113099)
[18:55:32] hb_scan: path=/home/thomasp/Video/Processing/Encode/Alien (1979).iso, title_index=1
[18:55:32] scan: trying to open with libdvdread
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[18:55:32] scan: DVD has 21 title(s)
[18:55:32] scan: scanning title 1
[18:55:32] scan: opening IFO for VTS 2
[18:55:32] pgc_id: 1, pgn: 1: pgc: 0x347d100
[18:55:32] scan: vts=2, ttn=1, cells=0->97, blocks=0->3810520, 3657724 blocks
[18:55:32] scan: duration is 01:55:42 (6942043 ms)
[18:55:32] scan: checking audio 1
[18:55:32] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[18:55:32] scan: checking audio 2
[18:55:32] scan: id=89bd, lang=English (DTS), 3cc=eng ext=1
[18:55:32] scan: checking audio 3
[18:55:32] scan: id=82bd, lang=Espanol (AC3), 3cc=spa ext=1
[18:55:32] scan: checking audio 4
[18:55:32] scan: id=83bd, lang=English (AC3), 3cc=eng ext=1
[18:55:32] scan: checking audio 5
[18:55:32] scan: id=84bd, lang=English (AC3), 3cc=eng ext=1
[18:55:32] scan: checking subtitle 1
[18:55:32] scan: id=21bd, lang=English (Closed Caption), 3cc=eng
[18:55:32] scan: checking subtitle 2
[18:55:32] scan: id=23bd, lang=Espanol, 3cc=spa
[18:55:32] scan: checking subtitle 3
[18:55:32] scan: id=25bd, lang=Espanol, 3cc=spa
[18:55:32] scan: checking subtitle 4
[18:55:32] scan: id=27bd, lang=Unknown, 3cc=und
[18:55:32] scan: title 1 has 41 chapters
[18:55:32] scan: chap 1 c=0->1, b=0->59973 (59974), 120905 ms
[18:55:32] scan: chap 2 c=2->4, b=59974->127025 (67052), 157932 ms
[18:55:32] scan: chap 3 c=5->7, b=127026->208326 (79524), 192437 ms
[18:55:32] scan: chap 4 c=8->9, b=208327->251913 (39176), 76719 ms
[18:55:32] scan: chap 5 c=10->11, b=251914->335942 (84029), 209348 ms
[18:55:32] scan: chap 6 c=12->12, b=335943->390005 (54063), 95465 ms
[18:55:32] scan: chap 7 c=13->14, b=390006->559572 (166159), 350979 ms
[18:55:32] scan: chap 8 c=15->15, b=559573->635547 (75975), 196928 ms
[18:55:32] scan: chap 9 c=16->18, b=635548->689774 (48636), 100829 ms
[18:55:32] scan: chap 10 c=19->21, b=689775->764594 (71767), 164224 ms
[18:55:32] scan: chap 11 c=22->25, b=764595->907276 (132013), 240985 ms
[18:55:32] scan: chap 12 c=26->30, b=907277->1022062 (106024), 220891 ms
[18:55:32] scan: chap 13 c=31->32, b=1022063->1060756 (38694), 91759 ms
[18:55:32] scan: chap 14 c=33->36, b=1060757->1160532 (91330), 209562 ms
[18:55:32] scan: chap 15 c=37->39, b=1160533->1237349 (76817), 171358 ms
[18:55:32] scan: chap 16 c=40->42, b=1237350->1348156 (106447), 241655 ms
[18:55:32] scan: chap 17 c=43->44, b=1348157->1415234 (67078), 158079 ms
[18:55:32] scan: chap 18 c=45->48, b=1415235->1473918 (46875), 96867 ms
[18:55:32] scan: chap 19 c=49->49, b=1473919->1538660 (64742), 161050 ms
[18:55:32] scan: chap 20 c=50->52, b=1538661->1677049 (131605), 276695 ms
[18:55:32] scan: chap 21 c=53->53, b=1677050->1712790 (35741), 85464 ms
[18:55:32] scan: chap 22 c=54->55, b=1712791->1851643 (132190), 189961 ms
[18:55:32] scan: chap 23 c=56->57, b=1851644->2030985 (179342), 282636 ms
[18:55:32] scan: chap 24 c=58->60, b=2030986->2111619 (73274), 105071 ms
[18:55:32] scan: chap 25 c=61->64, b=2111620->2237445 (110460), 167007 ms
[18:55:32] scan: chap 26 c=65->65, b=2237446->2292904 (55459), 101616 ms
[18:55:32] scan: chap 27 c=66->67, b=2292905->2382416 (89512), 161092 ms
[18:55:32] scan: chap 28 c=68->70, b=2382417->2451640 (62669), 107821 ms
[18:55:32] scan: chap 29 c=71->72, b=2451641->2567993 (109908), 211180 ms
[18:55:32] scan: chap 30 c=73->75, b=2567994->2739243 (163469), 244870 ms
[18:55:32] scan: chap 31 c=76->79, b=2739244->2834942 (80443), 129967 ms
[18:55:32] scan: chap 32 c=80->80, b=2834943->2901658 (66716), 118874 ms
[18:55:32] scan: chap 33 c=81->81, b=2901659->2961730 (60072), 110736 ms
[18:55:32] scan: chap 34 c=82->83, b=2961731->3019140 (57410), 103803 ms
[18:55:32] scan: chap 35 c=84->84, b=3019141->3155180 (136040), 202108 ms
[18:55:32] scan: chap 36 c=85->86, b=3155181->3209572 (50208), 88933 ms
[18:55:32] scan: chap 37 c=87->91, b=3209573->3417472 (193783), 253710 ms
[18:55:32] scan: chap 38 c=92->92, b=3417473->3500271 (82799), 170102 ms
[18:55:32] scan: chap 39 c=93->95, b=3500272->3730181 (229910), 407281 ms
[18:55:32] scan: chap 40 c=96->96, b=3730182->3810515 (80334), 164944 ms
[18:55:32] scan: chap 41 c=97->97, b=3810516->3810520 (5), 176 ms
[18:55:32] scan: aspect = 0
[18:55:32] scan: decoding previews for title 1

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
[18:55:32] scan: title angle(s) 1
[18:55:32] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[18:55:32] scan: audio 0x89bd: DCA, rate=48000Hz, bitrate=768000 English (DTS) (5.1 ch)
[18:55:32] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (2.0 ch)
[18:55:32] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[18:55:32] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[18:55:32] scan: 10 previews, 720x480, 23.976 fps, autocrop = 54/58/0/2, aspect 16:9, PAR 32:27
[18:55:32] scan: title (0) job->width:720, job->height:304
[18:55:32] libhb: scan thread found 1 valid title(s)
[18:55:34] gtkgui: Modified Custom Preset: Encoding DVDs
[18:55:34] 1 job(s) to process
[18:55:34] starting job
[18:55:34] job configuration:
[18:55:34]  * source
[18:55:34]    + /home/thomasp/Video/Processing/Encode/Alien (1979).iso
[18:55:34]    + title 1, chapter(s) 1 to 40
[18:55:34]  * destination
[18:55:34]    + /home/thomasp/Video/Processing/Encoding/Alien (1979) (Special Edition).mkv
[18:55:34]    + container: Matroska (.mkv)
[18:55:34]      + chapter markers
[18:55:34]  * video track
[18:55:34]    + decoder: mpeg2
[18:55:34]      + bitrate 9800 kbps
[18:55:34]    + frame rate: same as source (around 23.976 fps)
[18:55:34]    + loose anamorphic
[18:55:34]      + modulus: 2
[18:55:34]      + storage dimensions: 720 * 480 -> 718 * 366, crop 55/59/0/2
[18:55:34]      + pixel aspect ratio: 425 / 359
[18:55:34]      + display dimensions: 850 * 366
[18:55:34]    + filters
[18:55:34]      + Detelecine (pullup) (default settings)
[18:55:34]      + Decomb (default settings)
[18:55:34]    + encoder: x264
[18:55:34]      + options: b-adapt=2:rc-lookahead=50:me=umh:subme=9:analyse=all
[18:55:34]      + quality: 20.00 (RF)
[18:55:34]  * subtitle track 1, English (Closed Caption) (id 21bd) Picture [VOBSUB] -> Pass-Through 
[18:55:34]  * audio track 0
[18:55:34]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[18:55:34]      + bitrate: 448 kbps, samplerate: 48000 Hz
[18:55:34]    + AC3 passthrough
libdvdread: Using libdvdcss version 1.2.10 for DVD access

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
[18:55:34] yadif thread started for segment 0
[18:55:34] yadif thread started for segment 1
[18:55:34] decomb thread started for segment 0
[18:55:34] decomb thread started for segment 1
[18:55:34] encx264: keyint-min: 24, keyint-max: 240
[18:55:34] encx264: encoding with stored aspect 425/359
[18:55:34] encx264: Encoding at constant RF 20.000000
x264 [info]: using SAR=425/359
x264 [info]: using cpu capabilities: MMX2 SSE2Slow
[18:55:34] reader: first SCR 146 id 224 DTS 15798
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
[18:55:34] sync: expecting 166462 video frames
[ac3 @ 0x7f0b51c06b50]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[18:55:34] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[18:55:34] sync: first pts is 3003
[18:59:05] mpeg2: "Chapter  2" (2) at frame 2896 time 10873863
[19:04:27] mpeg2: "Chapter  3" (3) at frame 6692 time 25123462
[19:04:31] 281.351776s: Film -> Video
[19:04:31] 281.818909s: Video -> Film
[19:12:03] mpeg2: "Chapter  4" (4) at frame 11323 time 42496560
[19:12:32] 480.325470s: Film -> Video
[19:12:34] 480.825958s: Video -> Film
[19:14:50] mpeg2: "Chapter  5" (5) at frame 13182 time 49463919
[19:22:26] mpeg2: "Chapter  6" (6) at frame 18214 time 68350230
[19:26:00] mpeg2: "Chapter  7" (7) at frame 20510 time 76968840
[19:29:54] 971.158508s: Film -> Video
[19:29:55] 971.625610s: Video -> Film
[19:39:07] mpeg2: "Chapter  8" (8) at frame 28905 time 108470751
[19:46:06] mpeg2: "Chapter  9" (9) at frame 33617 time 126158421
[19:49:36] sync: adding 70 ms of silence to audio 0  start 133831267, next 133824960
[19:49:37] 1487.016357s: Film -> Video
[19:49:38] 1487.516846s: Video -> Film
[19:50:11] mpeg2: "Chapter 10" (10) at frame 36075 time 135373899
[19:50:24] 1511.482178s: Film -> Video
[19:50:25] 1511.965820s: Video -> Film
[19:56:41] mpeg2: "Chapter 11" (11) at frame 40048 time 150277303
[19:57:13] 1681.225952s: Film -> Video
[19:57:14] 1681.726440s: Video -> Film
[20:57:57] sync: got 41256 frames, 166462 expected
[20:57:57] work: average encoding speed for job is 10.900879 fps
[20:57:57] reader: done. 12 scr changes
[20:57:57] mux: track 0, 41172 frames, 191019225 bytes, 889.79 kbps, fifo 8
[20:57:57] mux: track 1, 53669 frames, 96174848 bytes, 448.00 kbps, fifo 256
[20:57:57] mux: track 2, 138 frames, 248958 bytes, 1.16 kbps, fifo 128
[20:57:57] mpeg2 done: 41257 frames
[20:57:57] render: lost time: 60060 (20 frames)
[20:57:57] render: gained time: 60060 (78 frames) (0 not accounted for)
[20:57:57] render: average dropped frame duration: 3003
x264 [info]: frame I:326   Avg QP:16.52  size: 36840  PSNR Mean Y:46.94 U:49.55 V:50.40 Avg:47.72 Global:47.20
x264 [info]: frame P:14394 Avg QP:19.75  size:  8293  PSNR Mean Y:45.03 U:47.86 V:48.67 Avg:45.62 Global:44.80
x264 [info]: frame B:26454 Avg QP:22.62  size:  2255  PSNR Mean Y:43.41 U:47.28 V:48.13 Avg:44.39 Global:43.81
x264 [info]: consecutive B-frames:  1.9%  9.9% 76.0% 12.3%
x264 [info]: mb I  I16..4:  9.8% 74.1% 16.1%
x264 [info]: mb P  I16..4:  1.2%  4.6%  0.7%  P16..4: 46.6% 15.1% 15.0%  0.4%  0.5%    skip:16.0%
x264 [info]: mb B  I16..4:  0.1%  0.8%  0.1%  B16..8: 42.4%  0.9%  1.4%  direct: 3.4%  skip:50.8%  L0:41.5% L1:50.2% BI: 8.3%
x264 [info]: 8x8 transform intra:72.6% inter:76.5%
x264 [info]: coded y,uvDC,uvAC intra: 78.3% 80.7% 49.8% inter: 20.3% 24.8% 2.8%
x264 [info]: i16 v,h,dc,p: 40% 16%  8% 36%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu:  8%  7%  5% 11% 16% 15% 14% 12% 12%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 10%  7%  3% 11% 15% 16% 13% 13% 12%
x264 [info]: Weighted P-Frames: Y:16.6%
x264 [info]: ref P L0: 58.2% 14.8% 17.4%  7.4%  2.3%
x264 [info]: ref B L0: 82.2% 17.8%
x264 [info]: SSIM Mean Y:0.9787648
x264 [info]: PSNR Mean Y:44.006 U:47.501 V:48.335 Avg:44.847 Global:44.152 kb/s:889.95
[20:57:57] decomb: deinterlaced 0 | blended 4 | unfiltered 41231 | total 41235
[20:57:57] libhb: work result = 0
Everything in the log looks exactly the same as a normally occurring encode.

User avatar
Rodeo
HandBrake Team
Posts: 12119
Joined: Tue Mar 03, 2009 8:55 pm

Re: Video Encoding Hangs - Certain Movies

Post by Rodeo » Sat Dec 05, 2009 6:14 pm

This looks weird:
Gamzarme wrote:

Code: Select all

[18:55:34]    + loose anamorphic
[18:55:34]      + modulus: 2
Edit: But apparently isn't. I just don't know loose anamorphic very well…

User avatar
JohnAStebbins
HandBrake Team
Posts: 5408
Joined: Sat Feb 09, 2008 7:21 pm

Re: Video Encoding Hangs - Certain Movies

Post by JohnAStebbins » Sat Dec 05, 2009 7:10 pm

Hmm. My copy of aliens only has 21 chapters. Seems odd that yours would have 40. The log you provided indicates that the encode stopped and "successfully" shut down during chapter 11. Did you cancel it, or did it spontaneously quit at that point.

You should try changing the dvd access method from dvdread to dvdnav. See if that behaves any better. The option for this is in advanced preferences.

Gamzarme
Posts: 9
Joined: Thu Dec 03, 2009 7:18 pm

Re: Video Encoding Hangs - Certain Movies

Post by Gamzarme » Sat Dec 05, 2009 8:06 pm

You say yours has 21 chapters? I have a bit of a correction to make regarding the title referred to previous posts. The film I'm attempting to encode is the "Alien (Director's Cut) (2003)" (title 1, with 40 chapters, not counting the zero length chapter at the end) that came on the Quadrilogy 9-disc set released in 2003. There is also the original 1979 release including on the same disc (title 2, with 32 chapters).
A couple of other official releases exist: "The Alien Legacy" was released in 1999 and included the three movies out at the time whereas "Alien Triple Pack" is also out there and includes pretty much the same material. "Alien" has also seen single releases over the years. Anyway, there is a good chance that the chapters have been reapportioned and that you have a different edition.

I canceled the encode during chapter 11. I actually wasn't aware that it was ch. 11 but that number sounds very correct for the partial resulting file. I did manually stop the encode after it oddly hung in the same spot it had the two times prior. So, no, HandBrake didn't crash. I've not had HB crash out but only a couple of times. It's been really stable! =)

I'll change the dvd access method soon (after a couple of encodes finish) and see how it goes.

About the anamorphic settings, I'll be re-reading the wiki so's to get a fresh grasp on what is happening behind the scenes.

Gamzarme
Posts: 9
Joined: Thu Dec 03, 2009 7:18 pm

Re: Video Encoding Hangs - Certain Movies

Post by Gamzarme » Sun Dec 06, 2009 11:29 pm

Here's the encode run using dvdnav. It looks like dvdnav is used in-place of certain dvdread functions, and dvdread is then used for the rest.

Code: Select all

Handbrake Version: rev2965 (2009113099)
[13:34:31] hb_scan: path=/home/thomasp/Video/Processing/Encode/Alien (1979).iso, title_index=1
[13:34:31] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdnav: DVD Title: ALIEN_1979
libdvdnav: DVD Serial Number: 002C3E7ADVDSHRNK
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find map file '/home/thomasp/.dvdnav/ALIEN_1979.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:34:31] scan: DVD has 21 title(s)
[13:34:31] scan: scanning title 1
[13:34:31] scan: opening IFO for VTS 2
[13:34:31] pgc_id: 1, pgn: 1: pgc: 0x7fc894105430
[13:34:31] scan: vts=2, ttn=1, cells=0->97, blocks=0->3810520, 3657724 blocks
[13:34:31] scan: duration is 01:55:42 (6942043 ms)
[13:34:31] scan: checking audio 1
[13:34:31] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:34:31] scan: checking audio 2
[13:34:31] scan: id=89bd, lang=English (DTS), 3cc=eng ext=1
[13:34:31] scan: checking audio 3
[13:34:31] scan: id=82bd, lang=Espanol (AC3), 3cc=spa ext=1
[13:34:31] scan: checking audio 4
[13:34:31] scan: id=83bd, lang=English (AC3), 3cc=eng ext=1
[13:34:31] scan: checking audio 5
[13:34:31] scan: id=84bd, lang=English (AC3), 3cc=eng ext=1
[13:34:31] scan: checking subtitle 1
[13:34:31] scan: id=21bd, lang=English (Closed Caption), 3cc=eng
[13:34:31] scan: checking subtitle 2
[13:34:31] scan: id=23bd, lang=Espanol, 3cc=spa
[13:34:31] scan: checking subtitle 3
[13:34:31] scan: id=25bd, lang=Espanol, 3cc=spa
[13:34:31] scan: checking subtitle 4
[13:34:31] scan: id=27bd, lang=Unknown, 3cc=und
[13:34:31] scan: title 1 has 41 chapters
[13:34:31] scan: chap 1 c=0->1, b=0->59973 (59974), 120905 ms
[13:34:31] scan: chap 2 c=2->4, b=59974->127025 (67052), 157932 ms
[13:34:31] scan: chap 3 c=5->7, b=127026->208326 (79524), 192437 ms
[13:34:31] scan: chap 4 c=8->9, b=208327->251913 (39176), 76719 ms
[13:34:31] scan: chap 5 c=10->11, b=251914->335942 (84029), 209348 ms
[13:34:31] scan: chap 6 c=12->12, b=335943->390005 (54063), 95465 ms
[13:34:31] scan: chap 7 c=13->14, b=390006->559572 (166159), 350979 ms
[13:34:31] scan: chap 8 c=15->15, b=559573->635547 (75975), 196928 ms
[13:34:31] scan: chap 9 c=16->18, b=635548->689774 (48636), 100829 ms
[13:34:31] scan: chap 10 c=19->21, b=689775->764594 (71767), 164224 ms
[13:34:31] scan: chap 11 c=22->25, b=764595->907276 (132013), 240985 ms
[13:34:31] scan: chap 12 c=26->30, b=907277->1022062 (106024), 220891 ms
[13:34:31] scan: chap 13 c=31->32, b=1022063->1060756 (38694), 91759 ms
[13:34:31] scan: chap 14 c=33->36, b=1060757->1160532 (91330), 209562 ms
[13:34:31] scan: chap 15 c=37->39, b=1160533->1237349 (76817), 171358 ms
[13:34:31] scan: chap 16 c=40->42, b=1237350->1348156 (106447), 241655 ms
[13:34:31] scan: chap 17 c=43->44, b=1348157->1415234 (67078), 158079 ms
[13:34:31] scan: chap 18 c=45->48, b=1415235->1473918 (46875), 96867 ms
[13:34:31] scan: chap 19 c=49->49, b=1473919->1538660 (64742), 161050 ms
[13:34:31] scan: chap 20 c=50->52, b=1538661->1677049 (131605), 276695 ms
[13:34:31] scan: chap 21 c=53->53, b=1677050->1712790 (35741), 85464 ms
[13:34:31] scan: chap 22 c=54->55, b=1712791->1851643 (132190), 189961 ms
[13:34:31] scan: chap 23 c=56->57, b=1851644->2030985 (179342), 282636 ms
[13:34:31] scan: chap 24 c=58->60, b=2030986->2111619 (73274), 105071 ms
[13:34:31] scan: chap 25 c=61->64, b=2111620->2237445 (110460), 167007 ms
[13:34:31] scan: chap 26 c=65->65, b=2237446->2292904 (55459), 101616 ms
[13:34:31] scan: chap 27 c=66->67, b=2292905->2382416 (89512), 161092 ms
[13:34:31] scan: chap 28 c=68->70, b=2382417->2451640 (62669), 107821 ms
[13:34:31] scan: chap 29 c=71->72, b=2451641->2567993 (109908), 211180 ms
[13:34:31] scan: chap 30 c=73->75, b=2567994->2739243 (163469), 244870 ms
[13:34:31] scan: chap 31 c=76->79, b=2739244->2834942 (80443), 129967 ms
[13:34:31] scan: chap 32 c=80->80, b=2834943->2901658 (66716), 118874 ms
[13:34:31] scan: chap 33 c=81->81, b=2901659->2961730 (60072), 110736 ms
[13:34:31] scan: chap 34 c=82->83, b=2961731->3019140 (57410), 103803 ms
[13:34:31] scan: chap 35 c=84->84, b=3019141->3155180 (136040), 202108 ms
[13:34:31] scan: chap 36 c=85->86, b=3155181->3209572 (50208), 88933 ms
[13:34:31] scan: chap 37 c=87->91, b=3209573->3417472 (193783), 253710 ms
[13:34:31] scan: chap 38 c=92->92, b=3417473->3500271 (82799), 170102 ms
[13:34:31] scan: chap 39 c=93->95, b=3500272->3730181 (229910), 407281 ms
[13:34:31] scan: chap 40 c=96->96, b=3730182->3810515 (80334), 164944 ms
[13:34:31] scan: chap 41 c=97->97, b=3810516->3810520 (5), 176 ms
[13:34:31] scan: aspect = 0
[13:34:31] scan: decoding previews for title 1
[13:34:31] scan: title angle(s) 1
[13:34:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:34:31] scan: audio 0x89bd: DCA, rate=48000Hz, bitrate=768000 English (DTS) (5.1 ch)
[13:34:31] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (2.0 ch)
[13:34:31] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[13:34:31] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=96000 English (AC3) (Dolby Surround)
[13:34:31] scan: 10 previews, 720x480, 23.976 fps, autocrop = 54/58/0/2, aspect 16:9, PAR 32:27
[13:34:31] scan: title (0) job->width:720, job->height:304
[13:34:31] libhb: scan thread found 1 valid title(s)
[13:34:34] gtkgui: Modified Custom Preset: Encoding DVDs
[13:34:34] 1 job(s) to process
[13:34:34] starting job
[13:34:34] job configuration:
[13:34:34]  * source
[13:34:34]    + /home/thomasp/Video/Processing/Encode/Alien (1979).iso
[13:34:34]    + title 1, chapter(s) 1 to 40
[13:34:34]  * destination
[13:34:34]    + /home/thomasp/Video/Processing/Encoding/Alien (Director's Cut) (1979).mkv
[13:34:34]    + container: Matroska (.mkv)
[13:34:34]      + chapter markers
[13:34:34]  * video track
[13:34:34]    + decoder: mpeg2
[13:34:34]      + bitrate 9800 kbps
[13:34:34]    + frame rate: same as source (around 23.976 fps)
[13:34:34]    + loose anamorphic
[13:34:34]      + modulus: 2
[13:34:34]      + storage dimensions: 720 * 480 -> 718 * 366, crop 55/59/0/2
[13:34:34]      + pixel aspect ratio: 425 / 359
[13:34:34]      + display dimensions: 850 * 366
[13:34:34]    + filters
[13:34:34]      + Detelecine (pullup) (default settings)
[13:34:34]      + Decomb (default settings)
[13:34:34]    + encoder: x264
[13:34:34]      + options: b-adapt=2:rc-lookahead=50:me=umh:subme=9:analyse=all
[13:34:34]      + quality: 20.00 (RF)
[13:34:34]  * subtitle track 1, English (Closed Caption) (id 21bd) Picture [VOBSUB] -> Pass-Through 
[13:34:34]  * audio track 0
[13:34:34]    + decoder: English (AC3) (5.1 ch) (track 1, id 80bd)
[13:34:34]      + bitrate: 448 kbps, samplerate: 48000 Hz
[13:34:34]    + AC3 passthrough
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdnav: DVD Title: ALIEN_1979
libdvdnav: DVD Serial Number: 002C3E7ADVDSHRNK
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find map file '/home/thomasp/.dvdnav/ALIEN_1979.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000141
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000043b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004ab
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000004fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0002fdb6
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003d22fa
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003d3229
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003d4ab7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003da36d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_0.VOB at 0x003dc990
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x003dc9f8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_08_1.VOB at 0x003df3ae
libdvdread: Elapsed time 0
libdvdread: Found 8 VTS's
libdvdread: Elapsed time 0
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:34:34] yadif thread started for segment 0
[13:34:34] yadif thread started for segment 1
[13:34:34] decomb thread started for segment 0
[13:34:34] decomb thread started for segment 1
[13:34:34] encx264: keyint-min: 24, keyint-max: 240
[13:34:34] encx264: encoding with stored aspect 425/359
[13:34:34] encx264: Encoding at constant RF 20.000000
[13:34:34] reader: first SCR 146 id 224 DTS 15798
[13:34:34] mpeg2: "Chapter  1" (1) at frame 0 time 3003
x264 [info]: using SAR=425/359
x264 [info]: using cpu capabilities: MMX2 SSE2Slow
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
[13:34:34] sync: expecting 166462 video frames
[ac3 @ 0x7fc894beddf0]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[13:34:34] sync: first pts is 3003
[13:35:08] hb_scan: path=/home/thomasp/Video/Processing/Encode (Local)/GLADIATOR EXTENDED EDITION/VIDEO_TS, title_index=0
[13:35:08] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/thomasp/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[13:35:08] scan: DVD has 8 title(s)
[13:35:08] scan: scanning title 1
[13:35:08] scan: opening IFO for VTS 1
[13:35:08] pgc_id: 1, pgn: 1: pgc: 0x3c358b0
[13:35:08] scan: vts=1, ttn=1, cells=0->29, blocks=0->3761479, 3761480 blocks
[13:35:08] scan: duration is 02:43:52 (9832020 ms)
[13:35:08] scan: checking audio 1
[13:35:08] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:35:08] scan: checking audio 2
[13:35:08] scan: id=81bd, lang=Espanol (AC3), 3cc=spa ext=0
[13:35:08] scan: checking audio 3
[13:35:08] scan: id=82bd, lang=English (AC3) (Director's Commentary 1), 3cc=eng ext=3
[13:35:08] scan: checking subtitle 1
[13:35:08] scan: id=20bd, lang=English, 3cc=eng
[13:35:08] scan: checking subtitle 2
[13:35:08] scan: id=21bd, lang=Espanol, 3cc=spa
[13:35:08] scan: checking subtitle 3
[13:35:08] scan: id=22bd, lang=Portugues, 3cc=por
[13:35:08] scan: checking subtitle 4
[13:35:08] scan: id=23bd, lang=English (Director's Commentary), 3cc=eng
[13:35:08] scan: checking subtitle 5
[13:35:08] scan: id=24bd, lang=Espanol (Director's Commentary), 3cc=spa
[13:35:08] scan: checking subtitle 6
[13:35:08] scan: id=25bd, lang=Portugues (Director's Commentary), 3cc=por
[13:35:08] scan: checking subtitle 7
[13:35:08] scan: id=26bd, lang=English, 3cc=eng
[13:35:08] scan: checking subtitle 8
[13:35:08] scan: id=27bd, lang=Espanol, 3cc=spa
[13:35:08] scan: checking subtitle 9
[13:35:08] scan: id=28bd, lang=Portugues, 3cc=por
[13:35:08] scan: checking subtitle 10
[13:35:08] scan: id=29bd, lang=Espanol, 3cc=spa
[13:35:08] scan: title 1 has 29 chapters
[13:35:08] scan: chap 1 c=0->0, b=0->160249 (160250), 415512 ms
[13:35:08] scan: chap 2 c=1->1, b=160250->287353 (127104), 323360 ms
[13:35:08] scan: chap 3 c=2->2, b=287354->423614 (136261), 340497 ms
[13:35:08] scan: chap 4 c=3->3, b=423615->533605 (109991), 272440 ms
[13:35:08] scan: chap 5 c=4->4, b=533606->739772 (206167), 546702 ms
[13:35:08] scan: chap 6 c=5->5, b=739773->838873 (99101), 264282 ms
[13:35:08] scan: chap 7 c=6->6, b=838874->912106 (73233), 188346 ms
[13:35:08] scan: chap 8 c=7->7, b=912107->1063244 (151138), 385592 ms
[13:35:08] scan: chap 9 c=8->8, b=1063245->1161445 (98201), 232250 ms
[13:35:08] scan: chap 10 c=9->9, b=1161446->1255710 (94265), 238406 ms
[13:35:08] scan: chap 11 c=10->10, b=1255711->1407339 (151629), 380467 ms
[13:35:08] scan: chap 12 c=11->11, b=1407340->1585903 (178564), 436572 ms
[13:35:08] scan: chap 13 c=12->12, b=1585904->1788586 (202683), 529525 ms
[13:35:08] scan: chap 14 c=13->14, b=1788587->2003563 (214977), 588153 ms
[13:35:08] scan: chap 15 c=15->15, b=2003564->2187929 (184366), 450586 ms
[13:35:08] scan: chap 16 c=16->16, b=2187930->2287441 (99512), 253301 ms
[13:35:08] scan: chap 17 c=17->17, b=2287442->2518925 (231484), 597602 ms
[13:35:08] scan: chap 18 c=18->18, b=2518926->2619468 (100543), 266344 ms
[13:35:08] scan: chap 19 c=19->19, b=2619469->2709343 (89875), 237355 ms
[13:35:08] scan: chap 20 c=20->20, b=2709344->2802731 (93388), 229347 ms
[13:35:08] scan: chap 21 c=21->21, b=2802732->2965395 (162664), 437573 ms
[13:35:08] scan: chap 22 c=22->22, b=2965396->2989026 (23631), 70239 ms
[13:35:08] scan: chap 23 c=23->23, b=2989027->3219969 (230943), 616861 ms
[13:35:08] scan: chap 24 c=24->24, b=3219970->3318900 (98931), 261399 ms
[13:35:08] scan: chap 25 c=25->25, b=3318901->3439012 (120112), 318646 ms
[13:35:08] scan: chap 26 c=26->26, b=3439013->3538076 (99064), 251309 ms
[13:35:08] scan: chap 27 c=27->27, b=3538077->3640124 (102048), 243531 ms
[13:35:08] scan: chap 28 c=28->28, b=3640125->3761473 (121349), 454811 ms
[13:35:08] scan: chap 29 c=29->29, b=3761474->3761479 (6), 1000 ms
[13:35:08] scan: aspect = 0
[13:35:08] scan: scanning title 2
[13:35:08] scan: opening IFO for VTS 2
[13:35:08] pgc_id: 1, pgn: 1: pgc: 0x3cf3540
[13:35:08] scan: vts=2, ttn=1, cells=0->1, blocks=0->9671, 9672 blocks
[13:35:08] scan: duration is 00:00:33 (33020 ms)
[13:35:08] scan: checking audio 1
[13:35:08] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:35:08] scan: checking subtitle 1
[13:35:08] scan: id=20bd, lang=English, 3cc=eng
[13:35:08] scan: checking subtitle 2
[13:35:08] scan: id=21bd, lang=Espanol, 3cc=spa
[13:35:08] scan: checking subtitle 3
[13:35:08] scan: id=22bd, lang=Portugues, 3cc=por
[13:35:08] scan: checking subtitle 4
[13:35:08] scan: id=23bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 5
[13:35:08] scan: id=24bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 6
[13:35:08] scan: id=25bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 7
[13:35:08] scan: id=26bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 8
[13:35:08] scan: id=27bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 9
[13:35:08] scan: id=28bd, lang=Unknown, 3cc=und
[13:35:08] scan: checking subtitle 10
[13:35:08] scan: id=29bd, lang=Unknown, 3cc=und
[13:35:08] scan: title 2 has 2 chapters
[13:35:08] scan: chap 1 c=0->0, b=0->9665 (9666), 32020 ms
[13:35:08] scan: chap 2 c=1->1, b=9666->9671 (6), 1000 ms
[13:35:08] scan: aspect = 0
[13:35:08] scan: scanning title 3
[13:35:08] scan: opening IFO for VTS 3
[13:35:08] scan: ignoring title (too short)
[13:35:08] scan: scanning title 4
[13:35:08] scan: opening IFO for VTS 3
[13:35:08] scan: ignoring title (too short)
[13:35:08] scan: scanning title 5
[13:35:08] scan: opening IFO for VTS 4
[13:35:08] scan: ignoring title (too short)
[13:35:08] scan: scanning title 6
[13:35:08] scan: opening IFO for VTS 5
[13:35:08] pgc_id: 1, pgn: 1: pgc: 0x3cfbc20
[13:35:08] scan: vts=5, ttn=1, cells=0->1, blocks=0->9849, 9850 blocks
[13:35:08] scan: duration is 00:00:24 (24000 ms)
[13:35:08] scan: checking audio 1
[13:35:08] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:35:08] scan: title 6 has 2 chapters
[13:35:08] scan: chap 1 c=0->0, b=0->9843 (9844), 23000 ms
[13:35:08] scan: chap 2 c=1->1, b=9844->9849 (6), 1000 ms
[13:35:08] scan: aspect = 0
[13:35:08] scan: scanning title 7
[13:35:08] scan: opening IFO for VTS 6
[13:35:08] scan: ignoring title (too short)
[13:35:08] scan: scanning title 8
[13:35:08] scan: opening IFO for VTS 6
[13:35:08] scan: ignoring title (too short)
[13:35:08] scan: decoding previews for title 1
[13:35:08] scan: title angle(s) 1
[13:35:09] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[13:35:09] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=384000 Espanol (AC3) (5.1 ch)
[13:35:09] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Director's Commentary 1) (Dolby Surround)
[13:35:10] scan: 10 previews, 720x576, 25.000 fps, autocrop = 74/66/0/2, aspect 16:9, PAR 64:45
[13:35:10] scan: decoding previews for title 2
[13:35:10] scan: title angle(s) 1
[13:35:10] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[13:35:10] scan: 10 previews, 720x576, 25.000 fps, autocrop = 20/4/28/104, aspect 16:9, PAR 64:45
[13:35:10] scan: decoding previews for title 6
[13:35:10] scan: title angle(s) 1
[13:35:10] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=384000 English (AC3) (5.1 ch)
[13:35:11] scan: 10 previews, 720x576, 25.000 fps, autocrop = 2/2/10/8, aspect 16:9, PAR 64:45
[13:35:11] scan: title (0) job->width:720, job->height:304
[13:35:11] scan: title (1) job->width:592, job->height:384
[13:35:11] scan: title (2) job->width:704, job->height:400
[13:35:11] libhb: scan thread found 3 valid title(s)
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:36:02] hb_scan: path=/home/thomasp/Video/Processing/Encode/Babylon A.D. (2008).iso, title_index=0
[13:36:02] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdnav: DVD Title: BABYLON_AD_2008
libdvdnav: DVD Serial Number: 003CE7CCDVDSHRNK
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find map file '/home/thomasp/.dvdnav/BABYLON_AD_2008.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000137
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x000004df
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000004e4
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x000005d4
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x00007558
libdvdread: Elapsed time 1
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x001da8e8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x001db50c
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x001dcc7a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x001f21bf
libdvdread: Elapsed time 1
libdvdread: Get key for /VIDEO_TS/VTS_07_1.VOB at 0x00208896
libdvdread: Elapsed time 0
libdvdread: Found 7 VTS's
libdvdread: Elapsed time 2
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:36:04] scan: DVD has 11 title(s)
[13:36:04] scan: scanning title 1
[13:36:04] scan: opening IFO for VTS 2
[13:36:04] pgc_id: 1, pgn: 1: pgc: 0x3c35a90
[13:36:04] scan: vts=2, ttn=1, cells=0->24, blocks=0->1913696, 1913697 blocks
[13:36:04] scan: duration is 01:30:09 (5409186 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: checking audio 2
[13:36:04] scan: id=81bd, lang=Espanol (AC3), 3cc=spa ext=1
[13:36:04] scan: checking audio 3
[13:36:04] scan: id=82bd, lang=Francais (AC3), 3cc=fra ext=1
[13:36:04] scan: checking subtitle 1
[13:36:04] scan: id=21bd, lang=English (Closed Caption), 3cc=eng
[13:36:04] scan: checking subtitle 2
[13:36:04] scan: id=23bd, lang=Espanol, 3cc=spa
[13:36:04] scan: checking subtitle 3
[13:36:04] scan: id=25bd, lang=Espanol, 3cc=spa
[13:36:04] scan: checking subtitle 4
[13:36:04] scan: id=27bd, lang=Francais, 3cc=fra
[13:36:04] scan: title 1 has 21 chapters
[13:36:04] scan: chap 1 c=0->1, b=0->85069 (85070), 229451 ms
[13:36:04] scan: chap 2 c=2->2, b=85070->156831 (71762), 196256 ms
[13:36:04] scan: chap 3 c=3->4, b=156832->261373 (104542), 294713 ms
[13:36:04] scan: chap 4 c=5->5, b=261374->376474 (115101), 333428 ms
[13:36:04] scan: chap 5 c=6->6, b=376475->451569 (75095), 194410 ms
[13:36:04] scan: chap 6 c=7->8, b=451570->532636 (81067), 239320 ms
[13:36:04] scan: chap 7 c=9->9, b=532637->632815 (100179), 276367 ms
[13:36:04] scan: chap 8 c=10->11, b=632816->765176 (132361), 321610 ms
[13:36:04] scan: chap 9 c=12->12, b=765177->913039 (147863), 432719 ms
[13:36:04] scan: chap 10 c=13->13, b=913040->1012995 (99956), 264365 ms
[13:36:04] scan: chap 11 c=14->14, b=1012996->1093499 (80504), 282540 ms
[13:36:04] scan: chap 12 c=15->15, b=1093500->1153674 (60175), 163505 ms
[13:36:04] scan: chap 13 c=16->16, b=1153675->1260855 (107181), 318409 ms
[13:36:04] scan: chap 14 c=17->17, b=1260856->1344129 (83274), 268380 ms
[13:36:04] scan: chap 15 c=18->18, b=1344130->1442553 (98424), 260327 ms
[13:36:04] scan: chap 16 c=19->19, b=1442554->1574194 (131641), 381645 ms
[13:36:04] scan: chap 17 c=20->20, b=1574195->1655394 (81200), 234427 ms
[13:36:04] scan: chap 18 c=21->21, b=1655395->1720394 (65000), 159410 ms
[13:36:04] scan: chap 19 c=22->22, b=1720395->1768483 (48089), 140176 ms
[13:36:04] scan: chap 20 c=23->23, b=1768484->1913691 (145208), 417544 ms
[13:36:04] scan: chap 21 c=24->24, b=1913692->1913696 (5), 176 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 2
[13:36:04] scan: opening IFO for VTS 2
[13:36:04] scan: ignoring title (too short)
[13:36:04] scan: scanning title 3
[13:36:04] scan: opening IFO for VTS 3
[13:36:04] scan: ignoring title (too short)
[13:36:04] scan: scanning title 4
[13:36:04] scan: opening IFO for VTS 4
[13:36:04] pgc_id: 1, pgn: 1: pgc: 0x43930a0
[13:36:04] scan: vts=4, ttn=1, cells=0->1, blocks=0->5983, 5984 blocks
[13:36:04] scan: duration is 00:00:14 (14166 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 4 has 2 chapters
[13:36:04] scan: chap 1 c=0->0, b=0->5978 (5979), 13977 ms
[13:36:04] scan: chap 2 c=1->1, b=5979->5983 (5), 188 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 5
[13:36:04] scan: opening IFO for VTS 1
[13:36:04] scan: ignoring title (too short)
[13:36:04] scan: scanning title 6
[13:36:04] scan: opening IFO for VTS 5
[13:36:04] pgc_id: 1, pgn: 1: pgc: 0x4393240
[13:36:04] scan: vts=5, ttn=1, cells=0->3, blocks=0->87348, 87349 blocks
[13:36:04] scan: duration is 00:04:28 (268010 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 6 has 4 chapters
[13:36:04] scan: chap 1 c=0->0, b=0->45438 (45439), 145361 ms
[13:36:04] scan: chap 2 c=1->1, b=45439->72864 (27426), 88389 ms
[13:36:04] scan: chap 3 c=2->2, b=72865->87343 (14479), 34082 ms
[13:36:04] scan: chap 4 c=3->3, b=87344->87348 (5), 176 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 7
[13:36:04] scan: opening IFO for VTS 7
[13:36:04] pgc_id: 1, pgn: 1: pgc: 0x7128930
[13:36:04] scan: vts=7, ttn=1, cells=0->1, blocks=0->57444, 57445 blocks
[13:36:04] scan: duration is 00:03:01 (181033 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=Unknown (AC3), 3cc=und ext=0
[13:36:04] scan: title 7 has 2 chapters
[13:36:04] scan: chap 1 c=0->0, b=0->57439 (57440), 180856 ms
[13:36:04] scan: chap 2 c=1->1, b=57440->57444 (5), 176 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 8
[13:36:04] scan: opening IFO for VTS 6
[13:36:04] pgc_id: 1, pgn: 1: pgc: 0x3f3ec60
[13:36:04] scan: vts=6, ttn=1, cells=0->3, blocks=0->91831, 91832 blocks
[13:36:04] scan: duration is 00:04:36 (276143 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 8 has 4 chapters
[13:36:04] scan: chap 1 c=0->0, b=0->52840 (52841), 144358 ms
[13:36:04] scan: chap 2 c=1->1, b=52841->66949 (14109), 45105 ms
[13:36:04] scan: chap 3 c=2->2, b=66950->91826 (24877), 86502 ms
[13:36:04] scan: chap 4 c=3->3, b=91827->91831 (5), 176 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 9
[13:36:04] scan: opening IFO for VTS 6
[13:36:04] pgc_id: 2, pgn: 1: pgc: 0x3c269d0
[13:36:04] scan: vts=6, ttn=2, cells=0->1, blocks=0->91836, 52846 blocks
[13:36:04] scan: duration is 00:02:25 (145166 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 9 has 2 chapters
[13:36:04] scan: chap 1 c=0->0, b=0->52840 (52841), 144988 ms
[13:36:04] scan: chap 2 c=1->1, b=91832->91836 (5), 177 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 10
[13:36:04] scan: opening IFO for VTS 6
[13:36:04] pgc_id: 3, pgn: 1: pgc: 0x3f425c0
[13:36:04] scan: vts=6, ttn=3, cells=0->1, blocks=52841->91841, 14114 blocks
[13:36:04] scan: duration is 00:00:45 (45176 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 10 has 2 chapters
[13:36:04] scan: chap 1 c=0->0, b=52841->66949 (14109), 45000 ms
[13:36:04] scan: chap 2 c=1->1, b=91837->91841 (5), 176 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: scanning title 11
[13:36:04] scan: opening IFO for VTS 6
[13:36:04] pgc_id: 4, pgn: 1: pgc: 0x3f406b0
[13:36:04] scan: vts=6, ttn=4, cells=0->1, blocks=66950->91846, 24882 blocks
[13:36:04] scan: duration is 00:01:26 (86153 ms)
[13:36:04] scan: checking audio 1
[13:36:04] scan: id=80bd, lang=English (AC3), 3cc=eng ext=1
[13:36:04] scan: title 11 has 2 chapters
[13:36:04] scan: chap 1 c=0->0, b=66950->91826 (24877), 85977 ms
[13:36:04] scan: chap 2 c=1->1, b=91842->91846 (5), 175 ms
[13:36:04] scan: aspect = 0
[13:36:04] scan: decoding previews for title 1
[13:36:04] scan: title angle(s) 1
[13:36:04] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 Espanol (AC3) (Dolby Surround)
[13:36:04] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:36:04] scan: audio 0x82bd: AC-3, rate=48000Hz, bitrate=192000 Francais (AC3) (Dolby Surround)
[13:36:05] scan: 10 previews, 720x480, 23.976 fps, autocrop = 54/58/0/2, aspect 16:9, PAR 32:27
[13:36:05] scan: decoding previews for title 4
[13:36:05] scan: title angle(s) 1
[13:36:05] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:36:05] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[13:36:05] scan: decoding previews for title 6
[13:36:05] scan: title angle(s) 1
[13:36:05] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[13:36:06] scan: 10 previews, 720x480, 23.976 fps, autocrop = 64/102/0/0, aspect 4:3, PAR 8:9
[13:36:06] scan: decoding previews for title 7
[13:36:06] scan: title angle(s) 1
[13:36:06] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 Unknown (AC3) (2.0 ch)
[13:36:06] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 32:27
[13:36:06] scan: decoding previews for title 8
[13:36:06] scan: title angle(s) 1
[13:36:06] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[13:36:07] scan: 10 previews, 720x480, 29.970 fps, autocrop = 64/68/0/0, aspect 4:3, PAR 8:9
[13:36:07] scan: decoding previews for title 9
[13:36:07] scan: title angle(s) 1
[13:36:07] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[13:36:07] scan: 10 previews, 720x480, 29.970 fps, autocrop = 64/66/0/0, aspect 4:3, PAR 8:9
[13:36:07] Title is likely interlaced or telecined (7 out of 10 previews). You should do something about that.
[13:36:07] scan: decoding previews for title 10
[13:36:07] scan: title angle(s) 1
[13:36:07] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[13:36:08] scan: 10 previews, 720x480, 29.970 fps, autocrop = 60/64/2/2, aspect 4:3, PAR 8:9
[13:36:08] scan: decoding previews for title 11
[13:36:08] scan: title angle(s) 1
[13:36:08] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (2.0 ch)
[13:36:08] scan: 10 previews, 720x480, 23.976 fps, autocrop = 100/104/0/4, aspect 4:3, PAR 8:9
[13:36:08] scan: title (0) job->width:720, job->height:304
[13:36:08] scan: title (1) job->width:720, job->height:400
[13:36:08] scan: title (2) job->width:656, job->height:320
[13:36:08] scan: title (3) job->width:720, job->height:400
[13:36:08] scan: title (4) job->width:640, job->height:352
[13:36:08] scan: title (5) job->width:640, job->height:352
[13:36:08] scan: title (6) job->width:624, job->height:352
[13:36:08] scan: title (7) job->width:624, job->height:272
[13:36:08] libhb: scan thread found 8 valid title(s)
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:39:01] mpeg2: "Chapter  2" (2) at frame 2896 time 10873863
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:40:54] hb_scan: path=/home/thomasp/Video/Processing/Encode/A.I. (2001).iso, title_index=0
[13:40:54] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdnav: DVD Title: AI_2001
libdvdnav: DVD Serial Number: 0020EA73DVDSHRNK
libdvdnav: DVD Title (Alternative): 
libdvdnav: Unable to find map file '/home/thomasp/.dvdnav/AI_2001.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x0000013b
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000fd9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x0001c78e
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x003b61d5
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x003b61d9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x003eeeb0
libdvdread: Elapsed time 1
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x003eeeb4
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x003f0676
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x003f067a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x003f21f3
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x003f21f7
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x003f2256
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x003f225a
libdvdread: Elapsed time 0
libdvdread: Found 6 VTS's
libdvdread: Elapsed time 1
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:40:55] scan: DVD has 6 title(s)
[13:40:55] scan: scanning title 1
[13:40:55] scan: opening IFO for VTS 1
[13:40:55] pgc_id: 1, pgn: 1: pgc: 0x3f40da0
[13:40:55] scan: vts=1, ttn=1, cells=0->33, blocks=0->3774985, 3774986 blocks
[13:40:55] scan: duration is 02:25:42 (8742276 ms)
[13:40:55] scan: checking audio 1
[13:40:55] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:40:55] scan: checking audio 2
[13:40:55] scan: id=81bd, lang=English (AC3), 3cc=eng ext=0
[13:40:55] scan: checking audio 3
[13:40:55] scan: id=8abd, lang=English (DTS), 3cc=eng ext=0
[13:40:55] scan: checking audio 4
[13:40:55] scan: id=83bd, lang=Francais (AC3), 3cc=fra ext=0
[13:40:55] scan: checking subtitle 1
[13:40:55] scan: id=20bd, lang=English, 3cc=eng
[13:40:55] scan: checking subtitle 2
[13:40:55] scan: id=21bd, lang=Espanol, 3cc=spa
[13:40:55] scan: checking subtitle 3
[13:40:55] scan: id=22bd, lang=Francais, 3cc=fra
[13:40:55] scan: title 1 has 33 chapters
[13:40:55] scan: chap 1 c=0->0, b=0->178244 (178245), 428706 ms
[13:40:55] scan: chap 2 c=1->1, b=178245->256587 (78343), 182344 ms
[13:40:55] scan: chap 3 c=2->2, b=256588->374098 (117511), 310463 ms
[13:40:55] scan: chap 4 c=3->3, b=374099->449672 (75574), 187359 ms
[13:40:55] scan: chap 5 c=4->4, b=449673->524048 (74376), 191176 ms
[13:40:55] scan: chap 6 c=5->5, b=524049->592663 (68615), 157145 ms
[13:40:55] scan: chap 7 c=6->6, b=592664->689565 (96902), 262242 ms
[13:40:55] scan: chap 8 c=7->7, b=689566->724978 (35413), 99091 ms
[13:40:55] scan: chap 9 c=8->8, b=724979->809530 (84552), 215199 ms
[13:40:55] scan: chap 10 c=9->9, b=809531->853547 (44017), 112279 ms
[13:40:55] scan: chap 11 c=10->10, b=853548->945970 (92423), 248405 ms
[13:40:55] scan: chap 12 c=11->11, b=945971->1100070 (154100), 329314 ms
[13:40:55] scan: chap 13 c=12->12, b=1100071->1317029 (216959), 498637 ms
[13:40:55] scan: chap 14 c=13->13, b=1317030->1404184 (87155), 239421 ms
[13:40:55] scan: chap 15 c=14->14, b=1404185->1623354 (219170), 486470 ms
[13:40:55] scan: chap 16 c=15->15, b=1623355->1738708 (115354), 201362 ms
[13:40:55] scan: chap 17 c=16->17, b=1738709->2021894 (283186), 513651 ms
[13:40:55] scan: chap 18 c=18->18, b=2021895->2145220 (123326), 280259 ms
[13:40:55] scan: chap 19 c=19->19, b=2145221->2238794 (93574), 188184 ms
[13:40:55] scan: chap 20 c=20->20, b=2238795->2380307 (141513), 325477 ms
[13:40:55] scan: chap 21 c=21->21, b=2380308->2426074 (45767), 122113 ms
[13:40:55] scan: chap 22 c=22->22, b=2426075->2488754 (62680), 121298 ms
[13:40:55] scan: chap 23 c=23->23, b=2488755->2563563 (74809), 168331 ms
[13:40:55] scan: chap 24 c=24->24, b=2563564->2711770 (148207), 347487 ms
[13:40:55] scan: chap 25 c=25->25, b=2711771->2799707 (87937), 188174 ms
[13:40:55] scan: chap 26 c=26->26, b=2799708->2863037 (63330), 149148 ms
[13:40:55] scan: chap 27 c=27->27, b=2863038->3024556 (161519), 379571 ms
[13:40:55] scan: chap 28 c=28->28, b=3024557->3182016 (157460), 352326 ms
[13:40:55] scan: chap 29 c=29->29, b=3182017->3336365 (154349), 334309 ms
[13:40:55] scan: chap 30 c=30->30, b=3336366->3462344 (125979), 273272 ms
[13:40:55] scan: chap 31 c=31->31, b=3462345->3637687 (175343), 416461 ms
[13:40:55] scan: chap 32 c=32->32, b=3637688->3774980 (137293), 431585 ms
[13:40:55] scan: chap 33 c=33->33, b=3774981->3774985 (5), 1000 ms
[13:40:55] scan: aspect = 0
[13:40:55] scan: scanning title 2
[13:40:55] scan: opening IFO for VTS 2
[13:40:55] pgc_id: 1, pgn: 1: pgc: 0x7fc897c05b80
[13:40:55] scan: vts=2, ttn=1, cells=0->1, blocks=0->232641, 232642 blocks
[13:40:55] scan: duration is 00:12:06 (726310 ms)
[13:40:55] scan: checking audio 1
[13:40:55] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:40:55] scan: checking subtitle 1
[13:40:55] scan: id=20bd, lang=Espanol, 3cc=spa
[13:40:55] scan: checking subtitle 2
[13:40:55] scan: id=21bd, lang=Francais, 3cc=fra
[13:40:55] scan: title 2 has 2 chapters
[13:40:55] scan: chap 1 c=0->0, b=0->232636 (232637), 725310 ms
[13:40:55] scan: chap 2 c=1->1, b=232637->232641 (5), 1000 ms
[13:40:55] scan: aspect = 0
[13:40:55] scan: scanning title 3
[13:40:55] scan: opening IFO for VTS 3
[13:40:55] pgc_id: 1, pgn: 1: pgc: 0x7fc897c05b80
[13:40:55] scan: vts=3, ttn=1, cells=0->1, blocks=0->6063, 6064 blocks
[13:40:55] scan: duration is 00:00:20 (20166 ms)
[13:40:55] scan: checking audio 1
[13:40:55] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:40:55] scan: checking audio 2
[13:40:55] scan: id=89bd, lang=English (DTS), 3cc=eng ext=0
[13:40:55] scan: title 3 has 2 chapters
[13:40:55] scan: chap 1 c=0->0, b=0->6058 (6059), 19166 ms
[13:40:55] scan: chap 2 c=1->1, b=6059->6063 (5), 1000 ms
[13:40:55] scan: aspect = 0
[13:40:55] scan: scanning title 4
[13:40:55] scan: opening IFO for VTS 4
[13:40:55] pgc_id: 1, pgn: 1: pgc: 0x7fc897c0a660
[13:40:55] scan: vts=4, ttn=1, cells=0->1, blocks=0->7014, 7015 blocks
[13:40:55] scan: duration is 00:00:23 (23100 ms)
[13:40:55] scan: checking audio 1
[13:40:55] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[13:40:55] scan: title 4 has 2 chapters
[13:40:55] scan: chap 1 c=0->0, b=0->7009 (7010), 22100 ms
[13:40:55] scan: chap 2 c=1->1, b=7010->7014 (5), 1000 ms
[13:40:55] scan: aspect = 0
[13:40:55] scan: scanning title 5
[13:40:55] scan: opening IFO for VTS 5
[13:40:55] scan: ignoring title (too short)
[13:40:55] scan: scanning title 6
[13:40:55] scan: opening IFO for VTS 6
[13:40:55] pgc_id: 1, pgn: 1: pgc: 0x7fc896dce4e0
[13:40:55] scan: vts=6, ttn=1, cells=0->1, blocks=0->3547, 3548 blocks
[13:40:55] scan: duration is 00:00:18 (18000 ms)
[13:40:55] scan: title 6 has 2 chapters
[13:40:55] scan: chap 1 c=0->0, b=0->3542 (3543), 17000 ms
[13:40:55] scan: chap 2 c=1->1, b=3543->3547 (5), 1000 ms
[13:40:55] scan: aspect = 0
[13:40:55] scan: decoding previews for title 1
[13:40:55] scan: title angle(s) 1
[13:40:55] scan: audio 0x8abd: DCA, rate=48000Hz, bitrate=768000 English (DTS) (5.1 ch)
[13:40:55] scan: audio 0x83bd: AC-3, rate=48000Hz, bitrate=384000 Francais (AC3) (5.1 ch)
[13:40:55] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=448000 English (AC3) (5.1 ch)
[13:40:55] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[13:40:55] scan: 10 previews, 720x480, 23.976 fps, autocrop = 0/0/2/2, aspect 16:9, PAR 32:27
[13:40:55] scan: decoding previews for title 2
[13:40:55] scan: title angle(s) 1
[13:40:55] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[13:40:56] scan: 10 previews, 720x480, 29.970 fps, autocrop = 62/68/4/2, aspect 4:3, PAR 8:9
[13:40:56] scan: decoding previews for title 3
[13:40:56] scan: title angle(s) 1
[13:40:56] scan: audio 0x89bd: DCA, rate=48000Hz, bitrate=1536000 English (DTS) (5.1 ch)
[13:40:56] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[13:40:56] scan: 10 previews, 720x480, 29.970 fps, autocrop = 58/62/2/2, aspect 16:9, PAR 32:27
[13:40:56] scan: decoding previews for title 4
[13:40:56] scan: title angle(s) 1
[13:40:56] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (Dolby Surround)
[13:40:57] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/4/0, aspect 16:9, PAR 32:27
[13:40:57] scan: decoding previews for title 6
[13:40:57] scan: title angle(s) 1
[13:40:57] scan: 10 previews, 720x480, 29.970 fps, autocrop = 70/78/74/74, aspect 4:3, PAR 8:9
[13:40:57] scan: title (0) job->width:720, job->height:400
[13:40:57] scan: title (1) job->width:640, job->height:352
[13:40:57] scan: title (2) job->width:720, job->height:304
[13:40:57] scan: title (3) job->width:720, job->height:400
[13:40:57] scan: title (4) job->width:512, job->height:336
[13:40:57] libhb: scan thread found 5 valid title(s)
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[13:46:03] mpeg2: "Chapter  3" (3) at frame 6692 time 25123462
[13:46:07] 281.351776s: Film -> Video
[13:46:08] 281.818909s: Video -> Film
[13:54:30] mpeg2: "Chapter  4" (4) at frame 11323 time 42496560
[13:54:53] 480.325470s: Film -> Video
[13:54:55] 480.825958s: Video -> Film
[13:57:16] mpeg2: "Chapter  5" (5) at frame 13182 time 49463919
[14:04:35] mpeg2: "Chapter  6" (6) at frame 18214 time 68350230
[14:08:04] mpeg2: "Chapter  7" (7) at frame 20510 time 76968840
[14:12:03] 971.158508s: Film -> Video
[14:12:04] 971.625610s: Video -> Film
[14:25:39] mpeg2: "Chapter  8" (8) at frame 28905 time 108470751
[14:37:27] mpeg2: "Chapter  9" (9) at frame 33617 time 126158421
[14:41:56] sync: adding 70 ms of silence to audio 0  start 133831267, next 133824960
[14:41:59] 1487.016357s: Film -> Video
[14:42:00] 1487.516846s: Video -> Film
[14:42:48] mpeg2: "Chapter 10" (10) at frame 36075 time 135373899
[14:43:08] 1511.482178s: Film -> Video
[14:43:09] 1511.965820s: Video -> Film
[14:51:43] mpeg2: "Chapter 11" (11) at frame 40048 time 150277303
[14:52:23] 1681.225952s: Film -> Video
[14:52:25] 1681.726440s: Video -> Film
[14:56:21] sync: got 41256 frames, 166462 expected
[14:56:21] work: average encoding speed for job is 0.000000 fps
[14:56:21] mux: track 0, 41172 frames, 191019225 bytes, 889.79 kbps, fifo 16
[14:56:21] mux: track 1, 53669 frames, 96174848 bytes, 448.00 kbps, fifo 256
[14:56:21] mux: track 2, 138 frames, 248958 bytes, 1.16 kbps, fifo 128
[14:56:22] mpeg2 done: 41257 frames
[14:56:22] reader: done. 12 scr changes
[14:56:22] render: lost time: 60060 (20 frames)
[14:56:22] render: gained time: 60060 (78 frames) (0 not accounted for)
[14:56:22] render: average dropped frame duration: 3003
x264 [info]: frame I:326   Avg QP:16.52  size: 36840  PSNR Mean Y:46.94 U:49.55 V:50.40 Avg:47.72 Global:47.20
x264 [info]: frame P:14394 Avg QP:19.75  size:  8293  PSNR Mean Y:45.03 U:47.86 V:48.67 Avg:45.62 Global:44.80
x264 [info]: frame B:26454 Avg QP:22.62  size:  2255  PSNR Mean Y:43.41 U:47.28 V:48.13 Avg:44.39 Global:43.81
x264 [info]: consecutive B-frames:  1.9%  9.9% 76.0% 12.3%
x264 [info]: mb I  I16..4:  9.8% 74.1% 16.1%
x264 [info]: mb P  I16..4:  1.2%  4.6%  0.7%  P16..4: 46.6% 15.1% 15.0%  0.4%  0.5%    skip:16.0%
x264 [info]: mb B  I16..4:  0.1%  0.8%  0.1%  B16..8: 42.4%  0.9%  1.4%  direct: 3.4%  skip:50.8%  L0:41.5% L1:50.2% BI: 8.3%
x264 [info]: 8x8 transform intra:72.6% inter:76.5%
x264 [info]: coded y,uvDC,uvAC intra: 78.3% 80.7% 49.8% inter: 20.3% 24.8% 2.8%
x264 [info]: i16 v,h,dc,p: 40% 16%  8% 36%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu:  8%  7%  5% 11% 16% 15% 14% 12% 12%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 10%  7%  3% 11% 15% 16% 13% 13% 12%
x264 [info]: Weighted P-Frames: Y:16.6%
x264 [info]: ref P L0: 58.2% 14.8% 17.4%  7.4%  2.3%
x264 [info]: ref B L0: 82.2% 17.8%
x264 [info]: SSIM Mean Y:0.9787648
x264 [info]: PSNR Mean Y:44.006 U:47.501 V:48.335 Avg:44.847 Global:44.152 kb/s:889.95
[14:56:23] decomb: deinterlaced 0 | blended 4 | unfiltered 41231 | total 41235
Again, however, it hung at the same percentage. Obviously it was attempting to encode beyond that point, though. I let it sit there for about five minutes before stopping the encode manually. Every time, though, I have never had to kill the HB process. There's no lock-up, just an indefinite hang.

mel
Posts: 1
Joined: Fri Jan 01, 2010 4:18 pm

Re: Video Encoding Hangs - Certain Movies

Post by mel » Fri Jan 01, 2010 4:26 pm

I'm having a similar issue, they always get stuck at 99.99% but if I look at the movie in question everything is fine and it's complete.
I'm using the latest from svn, svn3052 (x86_64).

From the end of the log it seems everything is fine but it never goes to complete in the gui

Code: Select all

x264 [info]: PSNR Mean Y:42.984 U:47.159 V:48.065 Avg:44.008 Global:43.599 kb/s:1374.36
[15:40:27] mux: track 0, 155292 frames, 1067082745 bytes, 1374.27 kbps, fifo 32
[15:40:27] mux: track 1, 194117 frames, 298163712 bytes, 384.00 kbps, fifo 256
[15:40:27] mux: track 2, 194117 frames, 347857664 bytes, 448.00 kbps, fifo 256
[15:40:27] mux: track 3, 1051 frames, 2907524 bytes, 3.74 kbps, fifo 8
[15:40:27] decomb: deinterlaced 53906 | blended 9309 | unfiltered 92076 | total 155291
[15:40:27] libhb: work result = 0

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Sun Jan 17, 2010 12:59 pm

I am using the last no stable version (3036) and I had the same problem in linux.
The encoding hangs, no error messages in the log, and if I press the pause button, it pauses but it never returns to the enconding if you press the resume after that. I though it has to do with scratchs in the DVD read surface and I passed first from VTSfix but even that did not helped.

Here is the log from a DVD from my choldren:

Code: Select all

Handbrake Version: svn3036 (2009121901)
[17:40:06] hb_scan: path=/DVDs/LT_ROAD_RUNNER, title_index=1
[17:40:06] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/dcoun/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[17:40:06] scan: DVD has 1 title(s)
[17:40:06] scan: scanning title 1
[17:40:06] scan: opening IFO for VTS 1
[17:40:06] pgc_id: 1, pgn: 1: pgc: 0x7f545801d300
[17:40:06] scan: vts=1, ttn=1, cells=0->14, blocks=0->1941480, 1941481 blocks
[17:40:06] scan: duration is 01:40:01 (6001000 ms)
[17:40:06] scan: checking audio 1
[17:40:06] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[17:40:06] scan: checking audio 2
[17:40:06] scan: id=84bd, lang=Greek, Modern (AC3), 3cc=ell ext=0
[17:40:06] scan: checking subtitle 1
[17:40:06] scan: id=20bd, lang=English, 3cc=eng
[17:40:06] scan: checking subtitle 2
[17:40:06] scan: id=24bd, lang=Greek, Modern, 3cc=ell
[17:40:06] scan: title 1 has 15 chapters
[17:40:06] scan: chap 1 c=0->0, b=0->126750 (126751), 392305 ms
[17:40:06] scan: chap 2 c=1->1, b=126751->246343 (119593), 369428 ms
[17:40:06] scan: chap 3 c=2->2, b=246344->374003 (127660), 394407 ms
[17:40:06] scan: chap 4 c=3->3, b=374004->506072 (132069), 408598 ms
[17:40:06] scan: chap 5 c=4->4, b=506073->636005 (129933), 401453 ms
[17:40:06] scan: chap 6 c=5->5, b=636006->761165 (125160), 387302 ms
[17:40:06] scan: chap 7 c=6->6, b=761166->884311 (123146), 380396 ms
[17:40:06] scan: chap 8 c=7->7, b=884312->1007943 (123632), 382298 ms
[17:40:06] scan: chap 9 c=8->8, b=1007944->1136231 (128288), 397309 ms
[17:40:06] scan: chap 10 c=9->9, b=1136232->1253594 (117363), 363283 ms
[17:40:06] scan: chap 11 c=10->10, b=1253595->1369904 (116310), 359290 ms
[17:40:06] scan: chap 12 c=11->11, b=1369905->1510761 (140857), 435339 ms
[17:40:06] scan: chap 13 c=12->12, b=1510762->1678229 (167468), 516452 ms
[17:40:06] scan: chap 14 c=13->13, b=1678230->1807099 (128870), 398440 ms
[17:40:06] scan: chap 15 c=14->14, b=1807100->1941480 (134381), 414693 ms
[17:40:06] scan: aspect = 0
[17:40:06] scan: decoding previews for title 1
[17:40:06] scan: title angle(s) 1
[17:40:06] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (1.0 ch)
[17:40:06] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=192000 Greek, Modern (AC3) (1.0 ch)
[17:40:06] scan: 10 previews, 720x576, 25,000 fps, autocrop = 0/0/0/0, aspect 4:3, PAR 16:15
[17:40:06] scan: title (0) job->width:720, job->height:544
[17:40:06] libhb: scan thread found 1 valid title(s)
[17:40:09] gtkgui: Custom Preset: mine_cartoon
[17:40:09] 1 job(s) to process
[17:40:09] starting job
[17:40:09] sync: expecting 150049 video frames
[ac3 @ 0x7f545805cda0]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[17:40:09] job configuration:
[17:40:09]  * source
[17:40:09]    + /DVDs/LT_ROAD_RUNNER
[17:40:09]    + title 1, chapter(s) 1 to 15
[17:40:09]  * destination
[17:40:09]    + /DVDs/out/Lt Road Runner.mkv
[17:40:09]    + container: Matroska (.mkv)
[17:40:09]      + chapter markers
[17:40:09]  * video track
[17:40:09]    + decoder: mpeg2
[17:40:09]      + bitrate 9800 kbps
[17:40:09]    + frame rate: same as source (around 25,000 fps)
[17:40:09]    + loose anamorphic
[17:40:09]      + storage dimensions: 720 * 576 -> 720 * 576, crop 0/0/0/0
[17:40:09]      + pixel aspect ratio: 16 / 15
[17:40:09]      + display dimensions: 768 * 576
[17:40:09]    + filters
[17:40:09]      + Detelecine (pullup) (default settings)
[17:40:09]      + Decomb (default settings)
[17:40:09]      + Denoise (hqdn3d) (3:2:2:3)
[17:40:09]    + encoder: x264
[17:40:09]      + options: b-adapt=2:rc-lookahead=50:me=umh:subme=9:merange=24:ref=5:bframes=5:analyse=all
[17:40:09]      + quality: 23,00 (RF)
[17:40:09]  * subtitle track 2, Greek, Modern (id 24bd) Picture [VOBSUB] -> Pass-Through 
[17:40:09]  * subtitle track 1, English (id 20bd) Picture [VOBSUB] -> Pass-Through 
[17:40:09]  * audio track 0
[17:40:09]    + decoder: Greek, Modern (AC3) (1.0 ch) (track 2, id 84bd)
[17:40:09]      + bitrate: 192 kbps, samplerate: 48000 Hz
[17:40:09]    + AC3 passthrough
[17:40:09]  * audio track 1
[17:40:09]    + decoder: English (AC3) (1.0 ch) (track 1, id 80bd)
[17:40:09]      + bitrate: 192 kbps, samplerate: 48000 Hz
[17:40:09]    + AC3 passthrough
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/home/dcoun/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[17:40:09] yadif thread started for segment 0
[17:40:09] yadif thread started for segment 1
[17:40:09] yadif thread started for segment 2
[17:40:09] yadif thread started for segment 3
[17:40:09] decomb thread started for segment 0
[17:40:09] decomb thread started for segment 1
[17:40:09] decomb thread started for segment 2
[17:40:09] decomb thread started for segment 3
[17:40:09] reader: first SCR 146 id 224 DTS 22254
[17:40:09] mpeg2: "Chapter  1" (1) at frame 0 time 3600
[17:40:09] encx264: encoding with stored aspect 16/15
[17:40:09] encx264: Encoding at constant RF 23,000000
x264 [info]: using SAR=16/15
x264 [info]: using cpu capabilities: MMX2 SSE2Fast FastShuffle SSEMisalign LZCNT
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
No accelerated IMDCT transform found
[17:40:09] sync: first pts is 3600
[17:53:08] mpeg2: "Chapter  2" (2) at frame 9800 time 35283600
[17:53:09] sync: video time didn't advance - dropped 1 frames (delta 4 ms, current 35330400, next 35333634, dur 3234)
[18:04:52] sync: adding 79 ms of silence to audio 32957  start 68574205, next 68567040
[18:04:54] mpeg2: "Chapter  3" (3) at frame 19048 time 68572434
[18:15:50] sync: adding 99 ms of silence to audio 32957  start 104115211, next 104106240
[18:15:50] sync: adding 103 ms of silence to audio 33981  start 104115211, next 104105874
[18:15:52] mpeg2: "Chapter  4" (4) at frame 28920 time 104113405
[18:28:48] mpeg2: "Chapter  5" (5) at frame 39127 time 140860411
[18:40:18] sync: adding 74 ms of silence to audio 32957  start 177031701, next 177024960
[18:40:18] sync: adding 78 ms of silence to audio 33981  start 177031701, next 177024594
[18:40:19] mpeg2: "Chapter  6" (6) at frame 49175 time 177029942
[18:51:02] mpeg2: "Chapter  7" (7) at frame 58850 time 211861701
[19:02:14] sync: adding 87 ms of silence to audio 33981  start 246140922, next 246133074
[19:02:14] sync: adding 83 ms of silence to audio 32957  start 246140922, next 246133440
[19:02:15] mpeg2: "Chapter  8" (8) at frame 68372 time 246140523
[19:13:10] sync: adding 74 ms of silence to audio 33981  start 280524133, next 280517394
[19:13:10] sync: adding 70 ms of silence to audio 32957  start 280524133, next 280517760
[19:13:11] mpeg2: "Chapter  9" (9) at frame 77922 time 280520922
[19:24:44] mpeg2: "Chapter 10" (10) at frame 87847 time 316254133
[19:35:08] mpeg2: "Chapter 11" (11) at frame 96922 time 348925252
[19:44:53] sync: adding 98 ms of silence to audio 32957  start 381271913, next 381263040
[19:44:54] sync: adding 102 ms of silence to audio 33981  start 381271913, next 381262674
[19:44:56] mpeg2: "Chapter 12" (12) at frame 105907 time 381267971

This problem happens every time in the same place (70% encoding) but it does not happen with the stable windows version (0.9.4).

User avatar
Rodeo
HandBrake Team
Posts: 12119
Joined: Tue Mar 03, 2009 8:55 pm

Re: Video Encoding Hangs - Certain Movies

Post by Rodeo » Sun Jan 17, 2010 7:50 pm

dcoun wrote:This problem happens every time in the same place (70% encoding) but it does not happen with the stable windows version (0.9.4).
Could you provide the 0.9.4 log for comparison?

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Sun Jan 17, 2010 9:50 pm

Code: Select all

### CLI Query:  -i "E:\DVD\LT_ROAD_RUNNER" -t 1 --angle 1 -c 1-15 -o "C:\DVD\LT_ROAD_RUNNER.mkv" -f mkv -w 720 --loose-anamorphic  --decomb --denoise="medium" -e x264 -q 23 -a 2,1 -E ac3,ac3 -6 auto,auto -R Auto,Auto -B auto,32 -D 0,0 --subtitle scan,scan -x b-adapt=2:rc-lookahead=50:me=umh:subq=9:merange=24:ref=5:bframes=5:analyse=all -v 1

### User Query: True

#########################################

[04:40:55] hb_init: checking cpu count
[04:40:55] hb_init: starting libhb thread
HandBrake 0.9.4 (2009112300) - MinGW i386 - http://handbrake.fr
2 CPUs detected
Opening E:\DVD\LT_ROAD_RUNNER...
[04:40:55] hb_scan: path=E:\DVD\LT_ROAD_RUNNER, title_index=1
[04:40:55] scan: trying to open with libdvdread
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.
[04:40:55] scan: DVD has 1 title(s)
[04:40:55] scan: scanning title 1
[04:40:55] scan: opening IFO for VTS 1
[04:40:55] pgc_id: 1, pgn: 1: pgc: 0x157cea8
[04:40:55] scan: vts=1, ttn=1, cells=0->14, blocks=0->1941480, 1941481 blocks
[04:40:55] scan: duration is 01:40:01 (6001000 ms)
[04:40:55] scan: checking audio 1
[04:40:55] scan: id=80bd, lang=English (AC3), 3cc=eng ext=0
[04:40:55] scan: checking audio 2
[04:40:55] scan: id=84bd, lang=Greek, Modern (AC3), 3cc=ell ext=0
[04:40:55] scan: checking subtitle 1
[04:40:55] scan: id=20bd, lang=English, 3cc=eng
[04:40:55] scan: checking subtitle 2
[04:40:55] scan: id=24bd, lang=Greek, Modern, 3cc=ell
[04:40:55] scan: title 1 has 15 chapters
[04:40:55] scan: chap 1 c=0->0, b=0->126750 (126751), 392305 ms
[04:40:55] scan: chap 2 c=1->1, b=126751->246343 (119593), 369428 ms
[04:40:55] scan: chap 3 c=2->2, b=246344->374003 (127660), 394407 ms
[04:40:55] scan: chap 4 c=3->3, b=374004->506072 (132069), 408598 ms
[04:40:55] scan: chap 5 c=4->4, b=506073->636005 (129933), 401453 ms
[04:40:55] scan: chap 6 c=5->5, b=636006->761165 (125160), 387302 ms
[04:40:55] scan: chap 7 c=6->6, b=761166->884311 (123146), 380396 ms
[04:40:55] scan: chap 8 c=7->7, b=884312->1007943 (123632), 382298 ms
[04:40:55] scan: chap 9 c=8->8, b=1007944->1136231 (128288), 397309 ms
[04:40:55] scan: chap 10 c=9->9, b=1136232->1253594 (117363), 363283 ms
[04:40:55] scan: chap 11 c=10->10, b=1253595->1369904 (116310), 359290 ms
[04:40:55] scan: chap 12 c=11->11, b=1369905->1510761 (140857), 435339 ms
[04:40:55] scan: chap 13 c=12->12, b=1510762->1678229 (167468), 516452 ms
[04:40:55] scan: chap 14 c=13->13, b=1678230->1807099 (128870), 398440 ms
[04:40:55] scan: chap 15 c=14->14, b=1807100->1941480 (134381), 414693 ms
[04:40:55] scan: aspect = 0
[04:40:55] scan: decoding previews for title 1
[04:40:55] scan: title angle(s) 1
[04:40:55] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=192000 English (AC3) (1.0 ch)
[04:40:55] scan: audio 0x84bd: AC-3, rate=48000Hz, bitrate=192000 Greek, Modern (AC3) (1.0 ch)
Scanning title 1...
[04:40:55] scan: 10 previews, 720x576, 25.000 fps, autocrop = 0/0/0/0, aspect 4:3, PAR 16:15
[04:40:55] scan: title (0) job->width:720, job->height:544
[04:40:55] libhb: scan thread found 1 valid title(s)
+ title 1:
  + vts 1, ttn 1, cells 0->14 (1941481 blocks)
  + angle(s) 1
  + duration: 01:40:01
  + size: 720x576, pixel aspect: 16/15, display aspect: 1.33, 25.000 fps
  + autocrop: 0/0/0/0
  + chapters:
    + 1: cells 0->0, 126751 blocks, duration 00:06:32
    + 2: cells 1->1, 119593 blocks, duration 00:06:09
    + 3: cells 2->2, 127660 blocks, duration 00:06:34
    + 4: cells 3->3, 132069 blocks, duration 00:06:49
    + 5: cells 4->4, 129933 blocks, duration 00:06:41
    + 6: cells 5->5, 125160 blocks, duration 00:06:27
    + 7: cells 6->6, 123146 blocks, duration 00:06:20
    + 8: cells 7->7, 123632 blocks, duration 00:06:22
    + 9: cells 8->8, 128288 blocks, duration 00:06:37
    + 10: cells 9->9, 117363 blocks, duration 00:06:03
    + 11: cells 10->10, 116310 blocks, duration 00:05:59
    + 12: cells 11->11, 140857 blocks, duration 00:07:15
    + 13: cells 12->12, 167468 blocks, duration 00:08:36
    + 14: cells 13->13, 128870 blocks, duration 00:06:38
    + 15: cells 14->14, 134381 blocks, duration 00:06:55
  + audio tracks:
    + 1, English (AC3) (1.0 ch) (iso639-2: eng), 48000Hz, 192000bps
    + 2, Greek, Modern (AC3) (1.0 ch) (iso639-2: ell), 48000Hz, 192000bps
  + subtitle tracks:
    + 1, English (iso639-2: eng) (Bitmap)
    + 2, Greek, Modern (iso639-2: ell) (Bitmap)
Invalid sample rate 0, using input rate 48000
Invalid sample rate 0, using input rate 48000
Subtitle Scan Enabled - enabling subtitles if found for foreign language segments
[04:40:55] 2 job(s) to process
[04:40:55] starting job
[04:40:55] job configuration:
[04:40:55]  * source
[04:40:55]    + E:\DVD\LT_ROAD_RUNNER
[04:40:55]    + title 1, chapter(s) 1 to 15
[04:40:55]  * destination
[04:40:55]    + C:\DVD\LT_ROAD_RUNNER.mkv
[04:40:55]    + container: Matroska (.mkv)
[04:40:55]  * video track
[04:40:55]    + decoder: mpeg2
[04:40:55]      + bitrate 9800 kbps
[04:40:55]    + frame rate: same as source (around 25.000 fps)
[04:40:55]    + loose anamorphic
[04:40:55]      + modulus: 0
[04:40:55]      + storage dimensions: 720 * 576 -> 720 * 576, crop 0/0/0/0
[04:40:55]      + pixel aspect ratio: 16 / 15
[04:40:55]      + display dimensions: 768 * 576
[04:40:55]    + filters
[04:40:55]      + Decomb (default settings)
[04:40:55]      + Denoise (hqdn3d) (3:2:2:3)
[04:40:55]  * subtitle track 2, Greek, Modern (id 24bd) Picture [VOBSUB] -> Render/Burn in 
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.
[04:40:55] decomb thread started for segment 0
[04:40:55] decomb thread started for segment 1
[04:40:55] yadif thread started for segment 0
[04:40:55] yadif thread started for segment 1
[04:40:55] sync: expecting 150049 video frames
[04:44:38] reader: done. 14 scr changes
[04:44:38] sync: got 0 frames, 150049 expected
[04:44:38] work: average encoding speed for job is 0.000000 fps
[04:44:38] mpeg2 done: 0 frames
[04:44:38] render: lost time: 0 (0 frames)
[04:44:38] render: gained time: 0 (0 frames) (0 not accounted for)
[04:44:38] Subtitle stream 0x24bd 'Greek, Modern': 858 hits (0 forced)
[04:44:38] decomb: deinterlaced 0 | blended 0 | unfiltered 0 | total 0
[04:44:38] starting job
[04:44:38] job configuration:
[04:44:38]  * source
[04:44:38]    + E:\DVD\LT_ROAD_RUNNER
[04:44:38]    + title 1, chapter(s) 1 to 15
[04:44:38]  * destination
[04:44:38]    + C:\DVD\LT_ROAD_RUNNER.mkv
[04:44:38]    + container: Matroska (.mkv)
[04:44:38]  * video track
[04:44:38]    + decoder: mpeg2
[04:44:38]      + bitrate 9800 kbps
[04:44:38]    + frame rate: same as source (around 25.000 fps)
[04:44:38]    + loose anamorphic
[04:44:38]      + modulus: 0
[04:44:38]      + storage dimensions: 720 * 576 -> 720 * 576, crop 0/0/0/0
[04:44:38]      + pixel aspect ratio: 16 / 15
[04:44:38]      + display dimensions: 768 * 576
[04:44:38]    + filters
[04:44:38]      + Decomb (default settings)
[04:44:38]      + Denoise (hqdn3d) (3:2:2:3)
[04:44:38]    + encoder: x264
[04:44:38]      + options: b-adapt=2:rc-lookahead=50:me=umh:subq=9:merange=24:ref=5:bframes=5:analyse=all
[04:44:38]      + quality: 23.00 (RF)
[04:44:38]  * audio track 0
[04:44:38]    + decoder: Greek, Modern (AC3) (1.0 ch) (track 2, id 84bd)
[04:44:38]      + bitrate: 192 kbps, samplerate: 48000 Hz
[04:44:38]    + AC3 passthrough
[04:44:38]  * audio track 1
[04:44:38]    + decoder: English (AC3) (1.0 ch) (track 1, id 80bd)
[04:44:38]      + bitrate: 192 kbps, samplerate: 48000 Hz
[04:44:38]    + AC3 passthrough
libdvdnav: Using dvdnav version 4.1.3
[04:44:39] encx264: encoding with stored aspect 16/15
[04:44:39] encx264: Encoding at constant RF 23.000000
x264 [info]: using SAR=16/15
[04:44:39] yadif thread started for segment 0
[04:44:39] decomb thread started for segment 0
[04:44:39] decomb thread started for segment 1
[04:44:39] yadif thread started for segment 1
x264 [info]: using cpu capabilities: MMX2 Cache64
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.
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
No accelerated IMDCT transform found
[04:44:39] sync: expecting 150049 video frames
[04:44:39] reader: first SCR 146 id 224 DTS 22254
[ac3 @ 0x3dc7480]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[04:44:39] mpeg2: "" (1) at frame 0 time 3600
[04:44:39] sync: first pts is 3600
[05:30:35] mpeg2: "" (2) at frame 9800 time 35283600
[05:30:39] sync: video time didn't advance - dropped 1 frames (delta 4 ms, current 35330400, next 35333634, dur 3234)
[06:11:19] sync: adding 79 ms of silence to audio 1  start 68574205, next 68567040
[06:11:31] mpeg2: "" (3) at frame 19048 time 68572434
[06:48:47] sync: adding 103 ms of silence to audio 0  start 104115211, next 104105874
[06:48:59] mpeg2: "" (4) at frame 28920 time 104113405
[07:34:24] mpeg2: "" (5) at frame 39127 time 140860411
[08:12:59] sync: adding 106 ms of silence to audio 1  start 177031701, next 177022080
[08:13:09] mpeg2: "" (6) at frame 49175 time 177029942
[08:50:21] sync: adding 71 ms of silence to audio 0  start 211861323, next 211854902
[08:50:37] mpeg2: "" (7) at frame 58850 time 211861701
[09:29:57] mpeg2: "" (8) at frame 68372 time 246140523
[10:08:04] sync: adding 79 ms of silence to audio 0  start 280524133, next 280516982
[10:08:04] sync: adding 102 ms of silence to audio 1  start 280524133, next 280514880
[10:08:16] mpeg2: "" (9) at frame 77922 time 280520922
[10:48:36] mpeg2: "" (10) at frame 87847 time 316254133
[11:24:10] mpeg2: "" (11) at frame 96922 time 348925252
[11:57:45] sync: adding 75 ms of silence to audio 0  start 381271913, next 381265142
[11:57:57] mpeg2: "" (12) at frame 105907 time 381267971
[12:39:43] mpeg2: "" (13) at frame 116782 time 420421913
[13:47:18] sync: adding 74 ms of silence to audio 1  start 466897878, next 466891200
[13:47:30] mpeg2: "" (14) at frame 129693 time 466898267
[14:31:15] mpeg2: "" (15) at frame 139656 time 502764678
[15:22:12] reader: done. 14 scr changes
[15:22:26] sync: got 150023 frames, 150049 expected
[15:22:26] work: average encoding speed for job is 3.920336 fps
[15:22:33] mux: track 0, 150023 frames, 1371709925 bytes, 1828.64 kbps, fifo 64
[15:22:33] mux: track 1, 187531 frames, 144023808 bytes, 192.00 kbps, fifo 512
[15:22:33] mux: track 2, 187531 frames, 144023808 bytes, 192.00 kbps, fifo 512
[15:22:33] mpeg2 done: 150025 frames
[15:22:33] render: lost time: 0 (0 frames)
[15:22:33] render: gained time: 0 (0 frames) (0 not accounted for)
[15:22:33] encx264: 1 frames had to be split (150022 in, 150083 out)
x264 [info]: frame I:1094  Avg QP:20.68  size: 54534  PSNR Mean Y:45.50 U:47.19 V:46.99 Avg:45.87 Global:44.80
x264 [info]: frame P:61401 Avg QP:24.50  size: 14433  PSNR Mean Y:41.84 U:44.13 V:43.78 Avg:42.28 Global:40.90
x264 [info]: frame B:87528 Avg QP:26.45  size:  4866  PSNR Mean Y:39.85 U:43.01 V:42.73 Avg:40.57 Global:40.03
x264 [info]: consecutive B-frames:  7.3% 27.4% 51.5%  7.5%  4.4%  2.0%
x264 [info]: mb I  I16..4: 14.8% 78.3%  6.9%
x264 [info]: mb P  I16..4:  3.8%  7.7%  0.6%  P16..4: 47.6% 10.8% 12.3%  0.3%  0.3%    skip:16.7%
x264 [info]: mb B  I16..4:  0.3%  1.7%  0.1%  B16..8: 38.9%  0.8%  1.3%  direct: 6.8%  skip:50.0%  L0:38.0% L1:45.3% BI:16.7%
x264 [info]: 8x8 transform intra:68.4% inter:83.1%
x264 [info]: coded y,uvDC,uvAC intra: 64.0% 63.8% 34.2% inter: 26.6% 32.8% 5.4%
x264 [info]: i16 v,h,dc,p: 55% 23%  7% 15%
x264 [info]: i8 v,h,dc,ddl,ddr,vr,hd,vl,hu:  8%  7%  9% 12% 14% 13% 12% 12% 12%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 11% 10%  6% 10% 13% 13% 13% 11% 13%
x264 [info]: Weighted P-Frames: Y:24.7%
x264 [info]: ref P L0: 49.6% 11.7% 22.1%  4.8%  6.8%  3.5%  1.5%
x264 [info]: ref B L0: 76.2% 12.3%  7.3%  4.3%
x264 [info]: SSIM Mean Y:0.9633145
x264 [info]: PSNR Mean Y:40.708 U:43.500 V:43.187 Avg:41.304 Global:40.386 kb/s:1828.73
[15:22:35] decomb: deinterlaced 0 | blended 0 | unfiltered 150022 | total 150022
[15:22:35] libhb: work result = 0

Rip done!
HandBrake has exited.

User avatar
Rodeo
HandBrake Team
Posts: 12119
Joined: Tue Mar 03, 2009 8:55 pm

Re: Video Encoding Hangs - Certain Movies

Post by Rodeo » Mon Jan 18, 2010 1:50 am

dcoun wrote:

Code: Select all

Handbrake Version: svn3036 (2009121901)
[17:40:06] hb_scan: path=/DVDs/LT_ROAD_RUNNER, title_index=1

[17:40:09]    + filters
[17:40:09]      + Detelecine (pullup) (default settings)
[17:40:09]      + Decomb (default settings)
[17:40:09]      + Denoise (hqdn3d) (3:2:2:3)
[17:40:09]    + encoder: x264
[17:40:09]      + options: b-adapt=2:rc-lookahead=50:me=umh:subme=9:merange=24:ref=5:bframes=5:analyse=all
[17:40:09]      + quality: 23,00 (RF)
[17:40:09]  * subtitle track 2, Greek, Modern (id 24bd) Picture [VOBSUB] -> Pass-Through 
[17:40:09]  * subtitle track 1, English (id 20bd) Picture [VOBSUB] -> Pass-Through
[17:40:09]  * audio track 0
dcoun wrote:

Code: Select all

HandBrake 0.9.4 (2009112300) - MinGW i386 - http://handbrake.fr
2 CPUs detected
Opening E:\DVD\LT_ROAD_RUNNER...
[04:40:55] hb_scan: path=E:\DVD\LT_ROAD_RUNNER, title_index=1

[04:44:38]    + filters
[04:44:38]      + Decomb (default settings)
[04:44:38]      + Denoise (hqdn3d) (3:2:2:3)
[04:44:38]    + encoder: x264
[04:44:38]      + options: b-adapt=2:rc-lookahead=50:me=umh:subq=9:merange=24:ref=5:bframes=5:analyse=all
[04:44:38]      + quality: 23.00 (RF)
[04:44:38]  * audio track 0
Did you notice that you're not encoding the same source (one is ripped, the other encoded straight from disc), using different settings (filters and subtitle tracks), and that the locale of the machine(s) the encodes were run on probably isn't the same (RF 23,00 vs 23.00)?

With the logs you provided, it is impossible to narrow down the probable cause of failure since the HandBrake version, the source or the settings (or maybe even the machine) all differ.

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Wed Jan 20, 2010 8:46 pm

sorry for the delay in my response.

No, the disk is riped in the linux machine in a folder with anyDVD in a windows virtual machine.
The ripped movie was used as source in both cases, first linux and after failure in a different machine running windows.
The handbrake settings were set in past, first in the windows machine, then the profile was exported and imported to the linux machine's handbrake.

The audio and subtitles in the windows GUI version is a big problem for me and that is the reason for using the linux version.

Sorry, but can you tell me the exact cli command line for the window version to try it again?

User avatar
Rodeo
HandBrake Team
Posts: 12119
Joined: Tue Mar 03, 2009 8:55 pm

Re: Video Encoding Hangs - Certain Movies

Post by Rodeo » Wed Jan 20, 2010 10:09 pm

dcoun wrote:Sorry, but can you tell me the exact cli command line for the window version to try it again?
It's in your log:

Code: Select all

### CLI Query:  -i "E:\DVD\LT_ROAD_RUNNER" -t 1 --angle 1 -c 1-15 -o "C:\DVD\LT_ROAD_RUNNER.mkv" -f mkv -w 720 --loose-anamorphic  --decomb --denoise="medium" -e x264 -q 23 -a 2,1 -E ac3,ac3 -6 auto,auto -R Auto,Auto -B auto,32 -D 0,0 --subtitle scan,scan -x b-adapt=2:rc-lookahead=50:me=umh:subq=9:merange=24:ref=5:bframes=5:analyse=all -v 1
Just replace CLI Query with the path to HandBrakeCLI and the source and output paths, and redirect the CLI output to a file:

Code: Select all

/path/to/HandBrakeCLI  -i /DVDs/LT_ROAD_RUNNER -t 1 --angle 1 -c 1-15 -o "/DVDs/out/Lt Road Runner.mkv" -f mkv -w 720 --loose-anamorphic  --decomb --denoise="medium" -e x264 -q 23 -a 2,1 -E ac3,ac3 -6 auto,auto -R Auto,Auto -B auto,32 -D 0,0 --subtitle scan,scan -x b-adapt=2:rc-lookahead=50:me=umh:subq=9:merange=24:ref=5:bframes=5:analyse=all -v 1 &> /path/to/log/file
And if you're using the Linux GUI, just remove both subtitle tracks and disable detelecine to match the settings you used under Windows. If the Linux encode still fails, post the log.

RoverTX
Posts: 57
Joined: Sun Oct 18, 2009 9:41 pm

Re: Video Encoding Hangs - Certain Movies

Post by RoverTX » Thu Jan 21, 2010 5:51 am

Same Problem hangs at about 24% on a Mac 10.6 using 9.4 HB (Hope this is ok to post here, but since its the same problem thought it might be a good idea)

Will try on another computer with a different DVD player to see if this problem could be hardware related

Code: Select all

HandBrake Activity Log for /Users/---------/Desktop/Stuff/EP 7.mkv: 2010-01-20 22:05:26 -0600
Handbrake Version: 0.9.4 x86_64 (2009112300)

[22:05:26] macgui: Rip: Pending queue count is 5
[22:05:26] macgui: scanning specifically for title: 1
[22:05:26] macgui: performNewQueueScan currentQueueEncodeIndex is: 24
[22:05:26] hb_scan: path=/dev/disk1, title_index=1
[22:05:26] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
NAME OPEN FAILED
libdvdnav: Unable to find map file '/Users/---------/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000123
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000ea8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00041fe3
libdvdread: Elapsed time 0
libdvdread: Found 1 VTS's
libdvdread: Elapsed time 0
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[22:05:30] scan: DVD has 7 title(s)
[22:05:30] scan: scanning title 1
[22:05:30] scan: opening IFO for VTS 1
[22:05:30] dvd: Skipping multi-angle cells 0-1
[22:05:30] dvd: Skipping multi-angle cells 4-5
[22:05:30] dvd: Skipping multi-angle cells 8-9
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 4-5
[22:05:30] dvd: Skipping multi-angle cells 8-9
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 4-5
[22:05:30] dvd: Skipping multi-angle cells 8-9
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 8-9
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] pgc_id: 1, pgn: 1: pgc: 0x13014ed40
[22:05:30] scan: vts=1, ttn=1, cells=0->27, blocks=343472->988065, 640356 blocks
[22:05:30] scan: duration is 00:24:10 (1450276 ms)
[22:05:30] scan: checking audio 1
[22:05:30] scan: id=80bd, lang=Japanese (AC3), 3cc=jpn ext=1
[22:05:30] scan: checking audio 2
[22:05:30] scan: id=81bd, lang=English (AC3), 3cc=eng ext=1
[22:05:30] scan: checking subtitle 1
[22:05:30] scan: id=20bd, lang=English, 3cc=eng
[22:05:30] scan: checking subtitle 2
[22:05:30] scan: id=21bd, lang=English, 3cc=eng
[22:05:30] scan: checking subtitle 3
[22:05:30] scan: id=22bd, lang=English, 3cc=eng
[22:05:30] scan: checking subtitle 4
[22:05:30] scan: id=23bd, lang=Espanol, 3cc=spa
[22:05:30] scan: checking subtitle 5
[22:05:30] scan: id=24bd, lang=Espanol, 3cc=spa
[22:05:30] scan: checking subtitle 6
[22:05:30] scan: id=25bd, lang=Espanol, 3cc=spa
[22:05:30] scan: title 1 has 10 chapters
[22:05:30] dvd: Skipping multi-angle cells 0-1
[22:05:30] dvd: Skipping multi-angle cells 4-5
[22:05:30] dvd: Skipping multi-angle cells 8-9
[22:05:30] dvd: Skipping multi-angle cells 10-11
[22:05:30] dvd: Skipping multi-angle cells 13-14
[22:05:30] dvd: Skipping multi-angle cells 15-16
[22:05:30] dvd: Skipping multi-angle cells 19-20
[22:05:30] dvd: Skipping multi-angle cells 21-22
[22:05:30] dvd: Skipping multi-angle cells 23-24
[22:05:30] dvd: Skipping multi-angle cells 25-26
[22:05:30] scan: chap 1 c=0->1, b=343472->425546 (81440), 99709 ms
[22:05:30] scan: chap 2 c=2->2, b=425547->436650 (11104), 35326 ms
[22:05:30] scan: chap 3 c=3->6, b=436651->519764 (82615), 209467 ms
[22:05:30] scan: chap 4 c=7->9, b=519765->593549 (73406), 195478 ms
[22:05:30] scan: chap 5 c=10->14, b=593550->716566 (122284), 316334 ms
[22:05:30] scan: chap 6 c=15->17, b=716567->791610 (74692), 181252 ms
[22:05:30] scan: chap 7 c=18->20, b=791611->908284 (116438), 313649 ms
[22:05:30] scan: chap 8 c=21->22, b=908285->974006 (65056), 79677 ms
[22:05:30] scan: chap 9 c=23->26, b=974007->987792 (13048), 18376 ms
[22:05:30] scan: chap 10 c=27->27, b=987793->988065 (273), 1004 ms
[22:05:30] scan: aspect = 0
[22:05:30] scan: decoding previews for title 1
[22:05:31] scan: title angle(s) 2
[22:05:31] scan: audio 0x80bd: AC-3, rate=48000Hz, bitrate=224000 Japanese (AC3) (2.0 ch)
[22:05:31] scan: audio 0x81bd: AC-3, rate=48000Hz, bitrate=224000 English (AC3) (2.0 ch)
[22:05:32] Warning: Could not read data for preview 6, skipped
[22:05:32] Warning: Could not read data for preview 7, skipped
[22:05:32] Warning: Could not read data for preview 8, skipped
[22:05:32] Warning: Could not read data for preview 9, skipped
[22:05:32] Warning: Could not read data for preview 10, skipped
[22:05:32] scan: 5 previews, 720x480, 29.970 fps, autocrop = 0/0/8/8, aspect 4:3, PAR 8:9
[22:05:32] Title is likely interlaced or telecined (2 out of 5 previews). You should do something about that.
[22:05:32] scan: title (0) job->width:624, job->height:480
[22:05:32] libhb: scan thread found 1 valid title(s)
[22:05:33] macgui: ScanDone state received from fQueueEncodeLibhb
[22:05:33] macgui: Preset: Anime_Final_Picture Size
[22:05:33] macgui: processNewQueueEncode number of passes expected is: 1
[22:05:33] macgui: Setting Subtitle: ½ 
[22:05:33] macgui: Setting Subtitle: ½!
[22:05:33] macgui: Setting Subtitle: ½"
[22:05:33] macgui: Setting Subtitle: ½#
[22:05:33] macgui: Setting Subtitle: ½$
[22:05:33] macgui: Setting Subtitle: ½%
[22:05:33] macgui: prepareJob audiotrack 2 is: 0
[22:05:33] macgui: prepareJob exiting
[22:05:33] 1 job(s) to process
[22:05:33] starting job
[22:05:33] job configuration:
[22:05:33]  * source
[22:05:33]    + /dev/disk1
[22:05:33]    + title 1, chapter(s) 1 to 10
[22:05:33]  * destination
[22:05:33]    + /Users/---------/Desktop/Rover's Stuff/EP 7.mkv
[22:05:33]    + container: Matroska (.mkv)
[22:05:33]      + chapter markers
[22:05:33]  * video track
[22:05:33]    + decoder: mpeg2
[22:05:33]      + bitrate 7500 kbps
[22:05:33]    + frame rate: same as source (around 29.970 fps)
[22:05:33]    + loose anamorphic
[22:05:33]      + modulus: 0
[22:05:33]      + storage dimensions: 720 * 480 -> 720 * 480, crop 0/0/0/0
[22:05:33]      + pixel aspect ratio: 8 / 9
[22:05:33]      + display dimensions: 640 * 480
[22:05:33]    + filters
[22:05:33]      + Detelecine (pullup) (default settings)
[22:05:33]      + Decomb (default settings)
[22:05:33]    + encoder: x264
[22:05:33]      + options: b-adapt=2:me=umh:subq=9:analyse=all:bframes=10:merange=24:no-fast-pskip=1:no-dct-decimate=1
[22:05:33]      + quality: 18.00 (RF)
[22:05:33]  * subtitle track 1, English (id 20bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * subtitle track 2, English (id 21bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * subtitle track 3, English (id 22bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * subtitle track 4, Espanol (id 23bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * subtitle track 5, Espanol (id 24bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * subtitle track 6, Espanol (id 25bd) Picture [VOBSUB] -> Pass-Through 
[22:05:33]  * audio track 0
[22:05:33]    + decoder: English (AC3) (2.0 ch) (track 2, id 81bd)
[22:05:33]      + bitrate: 224 kbps, samplerate: 48000 Hz
[22:05:33]    + mixdown: Stereo
[22:05:33]    + encoder: ca_aac
[22:05:33]      + bitrate: 224 kbps, samplerate: 48000 Hz
[22:05:33]  * audio track 1
[22:05:33]    + decoder: Japanese (AC3) (2.0 ch) (track 1, id 80bd)
[22:05:33]      + bitrate: 224 kbps, samplerate: 48000 Hz
[22:05:33]    + mixdown: Stereo
[22:05:33]    + encoder: ca_aac
[22:05:33]      + bitrate: 224 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[22:05:33] yadif thread started for segment 0
[22:05:33] yadif thread started for segment 1
[22:05:33] decomb thread started for segment 0
[22:05:33] decomb thread started for segment 1
[22:05:33] encx264: keyint-min: 30, keyint-max: 300
[22:05:33] encx264: encoding with stored aspect 8/9
[22:05:33] encx264: Encoding at constant RF 18.000000
x264 [info]: using SAR=8/9
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.1 Cache64
x264 [info]: profile High, level 3.0
No accelerated IMDCT transform found
No accelerated IMDCT transform found
[22:05:33] sync: expecting 43494 video frames
NAME OPEN FAILED
libdvdnav: Unable to find map file '/Users/---------/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00fe0000. Regions: 1

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000123
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00000ea8
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00041fe3
libdvdread: Elapsed time 0
libdvdread: Found 1 VTS's
libdvdread: Elapsed time 0
libdvdread: Using libdvdcss version 1.2.10 for DVD access
[22:05:34] reader: first SCR 184 id 224 DTS 17133
[22:05:34] mpeg2: "Chapter  1" (1) at frame 0 time 9009
[22:05:34] sync: first pts is 9009
[22:09:28] sync: adding 73 ms of silence to audio 0  start 8986477, next 8979840
[22:09:28] sync: adding 73 ms of silence to audio 1  start 8986477, next 8979840
[22:09:30] mpeg2: "Chapter  2" (2) at frame 2996 time 9005997
[22:10:25] mpeg2: "Chapter  3" (3) at frame 4070 time 12234853
[22:10:31] 140.344574s: Video -> Film
[22:10:32] 140.828384s: Film -> Video
[22:10:33] 141.178726s: Video -> Film
[22:10:36] 142.663544s: Film -> Video
[22:10:37] 143.080627s: Video -> Film
[22:10:43] 145.983536s: Film -> Video
[22:10:44] 146.167038s: Video -> Film
[22:11:11] 157.745285s: Film -> Video
[22:11:19] 161.098633s: Video -> Film
[22:11:37] sync: adding 89 ms of silence to audio 0  start 15263459, next 15255360
[22:11:37] sync: adding 89 ms of silence to audio 1  start 15263459, next 15255360
[22:11:38] 169.807327s: Film -> Video
[22:11:49] 175.409271s: Video -> Film
[22:11:52] 176.560410s: Film -> Video
[22:12:02] 181.048233s: Video -> Film
[22:12:05] 182.399582s: Film -> Video
[22:12:14] 186.153336s: Video -> Film
[22:12:22] 189.957138s: Film -> Video
[22:12:23] 190.490997s: Video -> Film
[22:12:31] 193.644135s: Film -> Video
[22:12:32] 193.827652s: Video -> Film
[22:12:32] 193.977829s: Film -> Video
[22:12:33] 194.161331s: Video -> Film
[22:12:34] 194.461639s: Film -> Video
[22:12:35] 195.262451s: Video -> Film
[22:12:36] 195.412582s: Film -> Video
[22:12:37] 195.762939s: Video -> Film
[22:12:37] 196.079910s: Film -> Video
[22:12:41] 198.098602s: Video -> Film
[22:12:41] 198.415604s: Film -> Video
[22:12:42] 198.932755s: Video -> Film
[22:12:43] 199.082916s: Film -> Video
[22:12:45] 200.100601s: Video -> Film
[22:12:47] 200.567734s: Film -> Video
[22:12:53] 202.669815s: Video -> Film
[22:12:53] 202.819977s: Film -> Video
[22:12:59] 205.639465s: Video -> Film
[22:12:59] 205.789597s: Film -> Video
[22:13:00] 205.973114s: Video -> Film
[22:13:00] 206.123291s: Film -> Video
[22:13:01] 206.406891s: Video -> Film
[22:13:02] 206.974136s: Film -> Video
[22:13:03] 207.341156s: Video -> Film
[22:13:03] 207.491333s: Film -> Video
[22:13:04] 207.608093s: Video -> Film
[22:13:04] 207.758240s: Film -> Video
[22:13:05] 207.908401s: Video -> Film
[22:13:08] 209.726883s: Film -> Video
[22:13:10] 210.577728s: Video -> Film
[22:13:13] 212.062561s: Film -> Video
[22:13:15] 212.579727s: Video -> Film
[22:13:15] 213.063553s: Film -> Video
[22:13:16] 213.247070s: Video -> Film
[22:13:16] 213.380539s: Film -> Video
[22:13:17] 213.564041s: Video -> Film
[22:13:18] 214.064529s: Film -> Video
[22:13:24] 217.317795s: Video -> Film
[22:13:24] 217.451263s: Film -> Video
[22:13:25] 217.734894s: Video -> Film
[22:13:25] 217.885025s: Film -> Video
[22:13:28] 218.819290s: Video -> Film
QuickNote: When I play the DVD on the Apple DVD player it works fine, but when I play it in VLC its clearly not doing something right because it doesn't let me skip around the Episiode, and skips around at a few points, the first skipping point being where it hangs on the encode. I have also tried the windows ver of handbrake to no luck, and also using libvdread for hanlding of the DVD structure.

QuickNote2: Just solved the problem I think, one of the subtitles is borked just got past the old stall point with out the subtitle streams off now to find out which one is doing it......Not related sorry at least I don't think.

QuickNote3: I think I may have figured this out. My problem was that the disks time stamping is messed up (i think or something related to it), which could be seen in VLC when it didn't allow me to skip around the episode but I could play through it. If there are subtitles this I think will mess with it big time and cause it to not be able to sync up, align up or something the subtitles with the video. This might be whats causing the hang up. I noticed that in all the failed encodes right before it stopped the subtitles all stopped working correctly, and on top of that when I remove the subtitles everything seems to work smoothly. dcoun logs would seem to verify this since the one he got to work was the one with out the subtitles! Then again I am no expert, but from my 5 hours of sitting in front of three computer trying to figure this out this is the best educated guess my small mind can come up with. I will post the log of the finished file with out the subtitles when it is finished.

QuickNote4: Video filters where the same both times default detelecine and decomb.

QuickNote5: One last thing I don't know if this is important or not but when it hung I opened up Activity monitor to make sure it was really hung, and it showed handbrake as using no processing power or near none.

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Sun Jan 24, 2010 5:17 pm

I respond with delay but I made a number of tests in the Unix version (3036) with GUI (the CLI version can not be installed in ubuntu karmic)
I run the same ripped DVD in the same ubuntu machine but after a reboot from previous posts' encodings with different settings (no update was made to any of the linux packages since my initial post):
a) without detelecine and without subtitles -> encode finished OK
b) without detelecine and with greek subtitles -> encode finished OK
c) without detelecine and with greek and english subtitles -> encode hangs in 84% (and not in 70% as before reboot)
d) with detelecine and with greek subtitles -> encode finished OK
e) with detelecine and with greek and english subtitles -> encode hangs in 84%
f) without detelecine and english subtitles -> encode hangs this time in 74% (in other place than before)

I wait for your suggestions. It is a bit crazy for me.

User avatar
Rodeo
HandBrake Team
Posts: 12119
Joined: Tue Mar 03, 2009 8:55 pm

Re: Video Encoding Hangs - Certain Movies

Post by Rodeo » Sun Jan 24, 2010 6:56 pm

dcoun wrote:a) without detelecine and without subtitles -> encode finished OK
b) without detelecine and with greek subtitles -> encode finished OK
c) without detelecine and with greek and english subtitles -> encode hangs in 84% (and not in 70% as before reboot)
d) with detelecine and with greek subtitles -> encode finished OK
e) with detelecine and with greek and english subtitles -> encode hangs in 84%
f) without detelecine and english subtitles -> encode hangs this time in 74% (in other place than before)
(emphasis mine)

There appears to be something in the English subtitle track that's causing HandBrake to hang (and my guess is that it's not specific to svn3036 - your 0.9.4 Windows encode didn't have the English subs which is probably why it didn't hang).

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Sun Jan 24, 2010 8:39 pm

Can we name it as a bug of Handbrake or it it something else that cause it?
Do you want something more to help you about?
Is there a way not to have this problem and include the english subtitles?

hkstar
Posts: 1
Joined: Mon Feb 22, 2010 2:15 pm

Re: Video Encoding Hangs - Certain Movies

Post by hkstar » Mon Feb 22, 2010 2:23 pm

I appear to have the same problem. It's svn 3036 on osx 10.6, x86_64. I have tried both kinds of DVD reading library, this is the non-default but the default stopped at an identical point. You can see the half-hour wait in the log time before I finally cancelled the queue. Here's the log.

Code: Select all

[00:25:20] hb_scan: path=/Volumes/LaCie/media/dvdrip/AVBG_72014/VIDEO_TS, title_index=1
[00:25:20] scan: trying to open with libdvdread
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/sho/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
[00:25:20] scan: DVD has 1 title(s)
[00:25:20] scan: scanning title 1
[00:25:20] scan: opening IFO for VTS 1
[00:25:20] pgc_id: 1, pgn: 1: pgc: 0x101620260
[00:25:20] scan: vts=1, ttn=1, cells=0->15, blocks=0->3826239, 3826236 blocks
[00:25:20] scan: duration is 01:56:17 (6977000 ms)
[00:25:20] scan: checking audio 1
[00:25:20] scan: id=a0bd, lang=Japanese (LPCM), 3cc=jpn ext=0
[00:25:20] scan: title 1 has 16 chapters
[00:25:20] scan: chap 1 c=0->0, b=0->176105 (176106), 336300 ms
[00:25:20] scan: chap 2 c=1->1, b=176106->381211 (205106), 379336 ms
[00:25:20] scan: chap 3 c=2->2, b=381212->685822 (304611), 564471 ms
[00:25:20] scan: chap 4 c=3->3, b=685823->847899 (162077), 300250 ms
[00:25:20] scan: chap 5 c=4->4, b=847900->1148317 (300418), 556684 ms
[00:25:20] scan: chap 6 c=5->5, b=1148318->1376764 (228447), 422672 ms
[00:25:20] scan: chap 7 c=6->6, b=1376765->1509709 (132945), 246371 ms
[00:25:20] scan: chap 8 c=7->7, b=1509710->1782611 (272902), 505431 ms
[00:25:20] scan: chap 9 c=8->8, b=1782612->1991993 (209382), 387343 ms
[00:25:20] scan: chap 10 c=9->9, b=1991998->2247213 (255216), 457381 ms
[00:25:20] scan: chap 11 c=10->10, b=2247214->2430817 (183604), 327283 ms
[00:25:20] scan: chap 12 c=11->11, b=2430818->2832472 (401655), 717618 ms
[00:25:20] scan: chap 13 c=12->12, b=2832473->3077348 (244876), 437364 ms
[00:25:20] scan: chap 14 c=13->13, b=3077349->3304382 (227034), 404623 ms
[00:25:20] scan: chap 15 c=14->14, b=3304383->3632727 (328345), 585554 ms
[00:25:20] scan: chap 16 c=15->15, b=3632728->3826239 (193512), 348310 ms
[00:25:20] scan: aspect = 0
[00:25:20] scan: decoding previews for title 1
libdvdnav: Language 'en' not found, using 'ja' instead
libdvdnav: Menu Languages available: ja 
[00:25:20] scan: title angle(s) 1
[00:25:20] scan: audio 0xa0bd: LPCM, rate=48000Hz, bitrate=1536000 Japanese (LPCM) (2.0 ch)
[00:25:20] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/2/8/6, aspect 4:3, PAR 8:9
[00:25:20] scan: title (0) job->width:624, job->height:480
[00:25:20] libhb: scan thread found 1 valid title(s)
[00:25:21] macgui: ScanDone state received from fQueueEncodeLibhb
[00:25:21] macgui: Preset: Custom
[00:25:21] macgui: processNewQueueEncode number of passes expected is: 2
[00:25:21] macgui: prepareJob exiting
[00:25:21] 2 job(s) to process
[00:25:21] starting job
[00:25:21] sync: expecting 209130 video frames
[00:25:21] job configuration:
[00:25:21]  * source
[00:25:21]    + /Volumes/LaCie/media/dvdrip/AVBG_72014/VIDEO_TS
[00:25:21]    + title 1, chapter(s) 1 to 16
[00:25:21]  * destination
[00:25:21]    + /Users/sho/Desktop/AVBG_72014.m4v
[00:25:21]    + container: MPEG-4 (.mp4 and .m4v)
[00:25:21]      + chapter markers
[00:25:21]  * video track
[00:25:21]    + decoder: mpeg2
[00:25:21]      + bitrate 9800 kbps
[00:25:21]    + frame rate: same as source (around 29.970 fps)
[00:25:21]    + loose anamorphic
[00:25:21]      + modulus: 0
[00:25:21]      + storage dimensions: 720 * 480 -> 720 * 480, crop 0/2/8/6
[00:25:21]      + pixel aspect ratio: 629 / 720
[00:25:21]      + display dimensions: 629 * 480
[00:25:21]    + filters
[00:25:21]      + Detelecine (pullup) (default settings)
[00:25:21]      + Decomb (default settings)
[00:25:21]    + encoder: x264
[00:25:21]      + options: b-adapt=2:rc-lookahead=50:ref=1:subme=2:me=dia:analyse=none:trellis=0:no-fast-pskip=0:8x8dct=0:weightb=0
[00:25:21]      + bitrate: 1500 kbps, pass: 1
[00:25:21]  * audio track 0
[00:25:21]    + decoder: Japanese (LPCM) (2.0 ch) (track 1, id a0bd)
[00:25:21]    + mixdown: Stereo
[00:25:21]    + encoder: ca_aac
[00:25:21]      + bitrate: 160 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 4.1.3
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Can't read name block. Probably not a DVD-ROM device.
libdvdnav: Unable to find map file '/Users/sho/.dvdnav/.map'
libdvdnav: DVD disk reports itself with Region mask 0x00000000. Regions: 1 2 3 4 5 6 7 8
[00:25:21] yadif thread started for segment 0
[00:25:21] yadif thread started for segment 1
[00:25:21] yadif thread started for segment 2
[00:25:21] yadif thread started for segment 3
[00:25:21] decomb thread started for segment 0
[00:25:21] encx264: keyint-min: 30, keyint-max: 300
[00:25:21] decomb thread started for segment 1
[00:25:21] encx264: encoding with stored aspect 629/720
[00:25:21] decomb thread started for segment 2
[00:25:21] decomb thread started for segment 3
x264 [info]: using SAR=629/720
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 FastShuffle SSE4.2
libdvdread: Using libdvdcss version 1.2.10 for DVD access
libdvdread: Couldn't find device name.
libdvdnav: Language 'en' not found, using 'ja' instead
libdvdnav: Menu Languages available: ja 
x264 [info]: profile Main, level 3.0
[00:25:21] reader: first SCR 146 id 224 DTS 17133
[00:25:21] mpeg2: "Chapter  1" (1) at frame 0 time 3003
[00:25:21] sync: first pts is 3003
[00:27:10] mpeg2: "Chapter  2" (2) at frame 10100 time 30333303
[00:29:31] mpeg2: "Chapter  3" (3) at frame 21490 time 64537473
[00:32:50] mpeg2: "Chapter  4" (4) at frame 38410 time 115348233
[00:34:41] mpeg2: "Chapter  5" (5) at frame 47410 time 142375233
[00:37:49] mpeg2: "Chapter  6" (6) at frame 64116 time 192543351
[00:40:24] mpeg2: "Chapter  7" (7) at frame 76805 time 230648418
[00:41:48] mpeg2: "Chapter  8" (8) at frame 84190 time 252825573
[00:44:54] mpeg2: "Chapter  9" (9) at frame 99350 time 298351053
[00:47:12] mpeg2: "Chapter 10" (10) at frame 110980 time 333275943
[01:13:03] macgui: Rip: Pending queue count is 0
[01:13:10] macgui: cancelling current job and stopping the queue
[01:13:10] reader: done. 1 scr changes
[01:13:10] sync: got 111005 frames, 209130 expected
[01:13:10] work: average encoding speed for job is 84.609650 fps
[01:13:10] mpeg2 done: 111006 frames
[01:13:10] render: lost time: 5459454 (1818 frames)
[01:13:10] render: gained time: 5459454 (6352 frames) (0 not accounted for)
[01:13:10] render: average dropped frame duration: 3003
[01:13:10] encx264: 1 frames had to be split (109183 in, 109182 out)
x264 [info]: frame I:1226  Avg QP:20.22  size: 28257  PSNR Mean Y:44.01 U:46.60 V:47.21 Avg:44.74 Global:44.36
x264 [info]: frame P:39019 Avg QP:23.11  size: 10127  PSNR Mean Y:41.52 U:44.56 V:45.35 Avg:42.36 Global:41.76
x264 [info]: frame B:68875 Avg QP:24.76  size:  3583  PSNR Mean Y:40.43 U:44.11 V:45.08 Avg:41.39 Global:40.75
x264 [info]: consecutive B-frames:  1.7% 22.8% 50.5% 25.0%
x264 [info]: mb I  I16..4: 46.9%  0.0% 53.1%
x264 [info]: mb P  I16..4: 31.5%  0.0%  0.0%  P16..4: 49.8%  0.0%  0.0%  0.0%  0.0%    skip:18.6%
x264 [info]: mb B  I16..4:  5.5%  0.0%  0.0%  B16..8: 28.7%  0.0%  0.0%  direct:16.7%  skip:49.1%  L0:33.4% L1:45.4% BI:21.3%
x264 [info]: final ratefactor: 21.45
x264 [info]: coded y,uvDC,uvAC intra: 39.5% 60.6% 20.7% inter: 18.5% 25.3% 1.6%
x264 [info]: i16 v,h,dc,p: 34% 25% 23% 17%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 19% 21% 16%  7%  8%  7%  8%  6%  8%
x264 [info]: Weighted P-Frames: Y:10.8%
x264 [info]: SSIM Mean Y:0.9651605
x264 [info]: PSNR Mean Y:40.865 U:44.299 V:45.202 Avg:41.776 Global:41.117 kb/s:1486.49
[01:13:10] decomb: deinterlaced 60268 | blended 13573 | unfiltered 35345 | total 109186
[01:13:10] libhb: work result = 0
I've encoded many DVDs happily with handbrake and never had this problem - I'm guessing it's pretty rare! Anyway, hope that helps if only a little.

Oh one more thing. I am willing to make the DVD image that produced the error available to handbrake devs if they want to check it locally. It's nearly 8G but I can arrange transfer and hosting if that will help at all.

TedJ
Veteran User
Posts: 5388
Joined: Wed Feb 20, 2008 11:25 pm

Re: Video Encoding Hangs - Certain Movies

Post by TedJ » Mon Feb 22, 2010 10:49 pm

What did you use to rip the DVD prior to encoding? Does it exhibit the same problem if you encode directly from the DVD?

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Wed Apr 07, 2010 2:28 pm

I believe that the problem has to do with the bad surface of the DVD that gives a ripped film that when you play it through vlc gives pixelazation at the moment of the film that ghb hangs. It does not have to do with the program you use to rip the DVD: I have checked the same disk with DVDfab, anydvd and k9copy.

I checked it when i found the following crazy behavior in an other DVD: Usually the ghb hangs and the filesize of the output file remains stable, but in an other DVD the ghb hanged and when I deleted the output mkv file I noticed that it was more than 10GB in size. I did again the encoding, the same thing happened and when I played the output encoded file I noticed that after a scene with pixelazation in the original vob files a steady pixelated images was till the end of the encoded file. I checked the original ripped vobs and I found that around this point of the film a lot of pixelazation exists due to surface errors from the DVD.

I believe that something either in the mpeg2 decoding, either in the re-encoding causes this hang.

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Wed Apr 07, 2010 9:11 pm

I think I found a solution but I am still in checking it.
I searched the preferences in file menu and I found that in order to read the ripped DVD either libdvdread, either dvdnav can be used. I had checked the dvdnav to be used and I uncheck it. Two ripped DVD in vobs format that used to hang, they are encoded now. I have not the time to watch the dvd from the encoded file to check it, but I will...

still checking the other ripped DVDs that made ghb to hang ...

dcoun
Posts: 8
Joined: Wed Dec 02, 2009 12:51 pm

Re: Video Encoding Hangs - Certain Movies

Post by dcoun » Thu Apr 08, 2010 2:51 pm

The results:
From five DVD that ghb hangs with dvdnav enabled, the three can be encoded with libdvdread without any problem. In the moment in the film that the ghb hangs with dvdnav enabled there is a less than a second loss of image/sound, but it finally finishes. It seems that programs which can clean vobs from DVD surface error and correct vob errors will not allow the ghb to do the encoding and the ghb hangs in the same percentage.

The problem continues in DVD with very bad DVD surface error where a big loss in successive sectors exists. I have no way to fix that for the moment except to re-encode the vobs with an other mpeg encoder like Nero's.

SnorreSelmer
Posts: 31
Joined: Thu Aug 14, 2008 7:02 pm

Re: Video Encoding Hangs - Certain Movies

Post by SnorreSelmer » Wed Apr 28, 2010 5:27 pm

I have a shell-script that I use under OSX and Linux to parse whole TV-series (10-50 DVDs pre-ripped to a folder).
I had to clone my original script and make a non-libdvdnav version because sometimes libdvdnav would cause problems when scanning titles (never while encoding though).
HB states that (at least for v0.9.4 Release, which is what I use) libdvdnav is experimental.

Post Reply