0.9.2 freeze on 10.5.2

[warped]

Hi,

I have issue with HB 0.9.2 running on Leo:
HB randomly freezes during second pass of transcoding. In such case CPU usage goes to 0 and i looks like HB is waiting for something. This freeze is usually on begining second pass (at 2-10% progress).
In such cases I tested 0.9.1 on the same source material and it works OK.
SYs is 10.5.2, CPU is Q6600 quad core
Here is log from freezed 0.9.2:

[12:29:33] hb_init: checking cpu count
[12:29:33] hb_init: starting libhb thread
[12:29:33] thread b0147000 started ("libhb")
[12:29:37] macgui: trying to open video_ts folder (video_ts folder chosen)
[12:29:37] macgui: trying to open a physical dvd at: /Volumes/Rodzina Pytalskich/VIDEO_TS
[12:29:37] hb_scan: path=/dev/disk2, title_index=0
[12:29:37] thread b0a3d000 started ("scan")
[12:29:37] scan: trying to open with libdvdread
[12:29:37] scan: DVD has 4 title(s)
[12:29:37] scan: scanning title 1
[12:29:37] scan: opening IFO for VTS 1
[12:29:37] pgc_id: 1, pgn: 1: pgc: 0xde85e30
[12:29:37] scan: vts=1, ttn=1, cells=0->10, blocks=0->978805, 978806 blocks
[12:29:37] scan: duration is 00:42:17 (2537360 ms)
[12:29:37] scan: checking audio 1
[12:29:37] scan: id=80bd, lang=Polish (AC3), 3cc=pol
[12:29:37] scan: title 1 has 11 chapters
[12:29:37] scan: chap 1 c=0->0, b=0->90546 (90547), 225517 ms
[12:29:37] scan: chap 2 c=1->1, b=90547->179182 (88636), 224375 ms
[12:29:37] scan: chap 3 c=2->2, b=179183->259595 (80413), 224375 ms
[12:29:37] scan: chap 4 c=3->3, b=259596->348960 (89365), 224646 ms
[12:29:37] scan: chap 5 c=4->4, b=348961->473041 (124081), 326531 ms
[12:29:37] scan: chap 6 c=5->5, b=473042->509119 (36078), 86276 ms
[12:29:37] scan: chap 7 c=6->6, b=509120->598548 (89429), 224446 ms
[12:29:37] scan: chap 8 c=7->7, b=598549->683803 (85255), 224556 ms
[12:29:37] scan: chap 9 c=8->8, b=683804->776097 (92294), 225597 ms
[12:29:37] scan: chap 10 c=9->9, b=776098->862646 (86549), 224516 ms
[12:29:37] scan: chap 11 c=10->10, b=862647->978805 (116159), 326521 ms
[12:29:37] scan: aspect = 12
[12:29:37] scan: scanning title 2
[12:29:37] scan: opening IFO for VTS 2
[12:29:37] pgc_id: 1, pgn: 1: pgc: 0xde85e30
[12:29:37] scan: vts=2, ttn=1, cells=0->0, blocks=0->2339, 2340 blocks
[12:29:37] scan: duration is 00:00:08 (8120 ms)
[12:29:37] scan: ignoring title (too short)
[12:29:37] scan: scanning title 3
[12:29:37] scan: opening IFO for VTS 2
[12:29:37] pgc_id: 2, pgn: 1: pgc: 0xde7beb0
[12:29:37] scan: vts=2, ttn=2, cells=0->0, blocks=2340->6024, 3685 blocks
[12:29:37] scan: duration is 00:00:09 (9130 ms)
[12:29:37] scan: ignoring title (too short)
[12:29:37] scan: scanning title 4
[12:29:37] scan: opening IFO for VTS 2
[12:29:37] pgc_id: 3, pgn: 1: pgc: 0x10904780
[12:29:37] scan: vts=2, ttn=3, cells=0->0, blocks=6025->39487, 33463 blocks
[12:29:37] scan: duration is 00:01:26 (86140 ms)
[12:29:37] scan: checking audio 1
[12:29:37] scan: id=80bd, lang=Polish (AC3), 3cc=pol
[12:29:37] scan: title 4 has 1 chapters
[12:29:37] scan: chap 1 c=0->0, b=6025->39487 (33463), 86140 ms
[12:29:37] scan: aspect = 12
[12:29:37] scan: decoding previews for title 1
[12:29:37] scan: preview 1
[12:29:38] scan: AC3, rate=48000Hz, bitrate=192000
[12:29:38] scan: preview 2
[12:29:38] scan: preview 3
[12:29:38] scan: preview 4
[12:29:38] scan: preview 5
[12:29:39] scan: preview 6
[12:29:39] scan: preview 7
[12:29:39] scan: preview 8
[12:29:39] scan: preview 9
[12:29:39] scan: preview 10
[12:29:40] scan: 720x576, 25.000 fps, autocrop = 0/0/2/12
[12:29:40] scan: decoding previews for title 4
[12:29:40] scan: preview 1
[12:29:40] scan: AC3, rate=48000Hz, bitrate=192000
[12:29:40] scan: preview 2
[12:29:40] scan: preview 3
[12:29:40] scan: preview 4
[12:29:40] scan: preview 5
[12:29:40] scan: preview 6
[12:29:40] scan: preview 7
[12:29:41] scan: preview 8
[12:29:41] scan: preview 9
[12:29:41] scan: preview 10
[12:29:41] scan: 720x576, 25.000 fps, autocrop = 0/0/2/12
[12:29:41] scan: title (0) job->width:704, job->height:544
[12:29:41] scan: title (1) job->width:704, job->height:544
[12:29:41] thread b0a3d000 exited ("scan")
[12:29:41] thread b0a3d000 joined ("scan")
[12:29:41] libhb: scan thread found 2 valid title(s)
[12:33:08] thread b066c000 started ("work")
[12:33:08] 2 job(s) to process
[12:33:08] starting job
[12:33:08] + device /dev/disk2
[12:33:08] + title 1, chapter(s) 1 to 11
[12:33:08] + 720x576 -> 704x544, crop 0/0/2/12
[12:33:08] + filters
[12:33:08] + Deinterlace (ffmpeg or yadif/mcdeint) (-1)
[12:33:08] + video frame rate: 25.000 fps
[12:33:08] + video bitrate 600 kbps, pass 1
[12:33:08] + PixelRatio: 0, width:704, height: 544
[12:33:08] + encoder x264
[12:33:08] + x264 options: ref=2:bframes=2:subq=5:me=umh:ref=1:subme=1:me=dia:analyse=none:trellis=0:no-fast-pskip=0:8x8dct=0:weightb=0
[12:33:08] + audio AC3 passthrough
[12:33:08] thread b06ff000 started ("reader")
[12:33:08] + output: /Users/piotro/Desktop/Rodzina Pytalskich.avi
[12:33:08] thread b0781000 started ("muxer")
[12:33:08] thread b0939000 started ("MPEG-2 decoder (libmpeg2)")
[12:33:08] thread b09bb000 started ("Renderer")
[12:33:08] encx264: opening libx264 (pass 1)
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 SSE3 SSSE3 Cache64
[12:33:08] thread b0a3d000 started ("H.264/AVC encoder (libx264)")
[12:33:08] sync: expecting 63459 video frames
[12:33:11] dvd: Beginning of Cell (0) at block 0
[12:33:11] sync: first pts is 25854
[12:33:11] macgui: Using preset: PiotrO
[12:34:20] dvd: End of Cell (0) at block 90348
[12:34:21] dvd: Beginning of Cell (1) at block 90547
[12:34:21] dvd: Chapter Break Cell Found
[12:34:21] MPEG2: Chapter Break Cell Found, searching for GOP
[12:34:21] MPEG2: Group of pictures found, searching for I-Frame
[12:34:21] MPEG2: I-Frame Found
[12:34:21] MPEG2: Chapter Break Inserted
[12:34:21] work Renderer: Copying Chapter Break @ 20278800
[12:35:31] dvd: End of Cell (1) at block 179115
[12:35:31] dvd: Beginning of Cell (2) at block 179183
[12:35:31] dvd: Chapter Break Cell Found
[12:35:31] MPEG2: Chapter Break Cell Found, searching for GOP
[12:35:31] MPEG2: Group of pictures found, searching for I-Frame
[12:35:31] MPEG2: I-Frame Found
[12:35:31] MPEG2: Chapter Break Inserted
[12:35:31] work Renderer: Copying Chapter Break @ 40518000
[12:36:36] dvd: End of Cell (2) at block 259457
[12:36:36] dvd: Beginning of Cell (3) at block 259596
[12:36:36] dvd: Chapter Break Cell Found
[12:36:36] MPEG2: Chapter Break Cell Found, searching for GOP
[12:36:36] MPEG2: Group of pictures found, searching for I-Frame
[12:36:36] MPEG2: I-Frame Found
[12:36:36] MPEG2: Chapter Break Inserted
[12:36:36] work Renderer: Copying Chapter Break @ 60757200
[12:37:47] dvd: End of Cell (3) at block 348883
[12:37:47] dvd: Beginning of Cell (4) at block 348961
[12:37:47] dvd: Chapter Break Cell Found
[12:37:47] MPEG2: Chapter Break Cell Found, searching for GOP
[12:37:47] MPEG2: Group of pictures found, searching for I-Frame
[12:37:47] MPEG2: I-Frame Found
[12:37:47] MPEG2: Chapter Break Inserted
[12:37:47] work Renderer: Copying Chapter Break @ 80985600
[12:39:28] dvd: End of Cell (4) at block 472741
[12:39:28] dvd: Beginning of Cell (5) at block 473042
[12:39:28] dvd: Chapter Break Cell Found
[12:39:28] MPEG2: Chapter Break Cell Found, searching for GOP
[12:39:28] MPEG2: Group of pictures found, searching for I-Frame
[12:39:28] MPEG2: I-Frame Found
[12:39:28] MPEG2: Chapter Break Inserted
[12:39:28] Sync: Video PTS discontinuity first (current buffer start=110387454, next buffer start=25854)
[12:39:28] work Renderer: Copying Chapter Break @ 110361600
[12:39:56] dvd: End of Cell (5) at block 508894
[12:39:57] dvd: Beginning of Cell (6) at block 509120
[12:39:57] dvd: Chapter Break Cell Found
[12:39:57] MPEG2: Chapter Break Cell Found, searching for GOP
[12:39:57] MPEG2: Group of pictures found, searching for I-Frame
[12:39:57] MPEG2: I-Frame Found
[12:39:57] MPEG2: Chapter Break Inserted
[12:39:57] work Renderer: Copying Chapter Break @ 118159200
[12:41:09] dvd: End of Cell (6) at block 598353
[12:41:09] dvd: Beginning of Cell (7) at block 598549
[12:41:09] dvd: Chapter Break Cell Found
[12:41:09] MPEG2: Chapter Break Cell Found, searching for GOP
[12:41:09] MPEG2: Group of pictures found, searching for I-Frame
[12:41:09] MPEG2: I-Frame Found
[12:41:09] MPEG2: Chapter Break Inserted
[12:41:09] work Renderer: Copying Chapter Break @ 138369600
[12:42:19] dvd: End of Cell (7) at block 683627
[12:42:20] dvd: Beginning of Cell (8) at block 683804
[12:42:20] dvd: Chapter Break Cell Found
[12:42:20] MPEG2: Chapter Break Cell Found, searching for GOP
[12:42:20] MPEG2: Group of pictures found, searching for I-Frame
[12:42:20] MPEG2: I-Frame Found
[12:42:20] MPEG2: Chapter Break Inserted
[12:42:20] work Renderer: Copying Chapter Break @ 158554800
[12:43:35] dvd: End of Cell (8) at block 775886
[12:43:35] dvd: Beginning of Cell (9) at block 776098
[12:43:35] dvd: Chapter Break Cell Found
[12:43:35] MPEG2: Chapter Break Cell Found, searching for GOP
[12:43:35] MPEG2: Group of pictures found, searching for I-Frame
[12:43:35] MPEG2: I-Frame Found
[12:43:35] MPEG2: Chapter Break Inserted
[12:43:35] work Renderer: Copying Chapter Break @ 178833600
[12:44:47] dvd: End of Cell (9) at block 862450
[12:44:47] dvd: Beginning of Cell (10) at block 862647
[12:44:47] dvd: Chapter Break Cell Found
[12:44:47] MPEG2: Chapter Break Cell Found, searching for GOP
[12:44:47] MPEG2: Group of pictures found, searching for I-Frame
[12:44:48] MPEG2: I-Frame Found
[12:44:48] MPEG2: Chapter Break Inserted
[12:44:48] work Renderer: Copying Chapter Break @ 199015200
[12:46:24] dvd: End of Cell (10) at block 978565
[12:46:24] reader: done
[12:46:24] thread b06ff000 exited ("reader")
[12:46:24] sync: got 63432 frames, 63459 expected
[12:46:24] thread b09bb000 exited ("Renderer")
[12:46:24] thread b0939000 exited ("MPEG-2 decoder (libmpeg2)")
[12:46:24] thread b0939000 joined ("MPEG-2 decoder (libmpeg2)")
[12:46:24] thread b0a3d000 exited ("H.264/AVC encoder (libx264)")
[12:46:24] thread b09bb000 joined ("Renderer")
[12:46:24] render: lost time: 0 (0 frames)
[12:46:24] render: gained time: 0 (0 frames) (0 not accounted for)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] thread b0a3d000 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:884 Avg QP:27.33 size: 12317 PSNR Mean Y:42.08 U:45.02 V:45.28 Avg:42.82 Global:41.84
x264 [info]: slice P:39515 Avg QP:29.29 size: 4089 PSNR Mean Y:39.26 U:42.62 V:42.98 Avg:40.10 Global:39.37
x264 [info]: slice B:23033 Avg QP:31.21 size: 804 PSNR Mean Y:38.63 U:42.06 V:42.48 Avg:39.49 Global:38.75
x264 [info]: mb I I16..4: 72.5% 0.0% 27.5%
x264 [info]: mb P I16..4: 15.1% 0.0% 0.0% P16..4: 24.4% 0.0% 0.0% 0.0% 0.0% skip:60.5%
x264 [info]: mb B I16..4: 0.4% 0.0% 0.0% B16..8: 7.0% 0.0% 0.0% direct: 4.7% skip:87.9%
x264 [info]: final ratefactor: 29.61
x264 [info]: SSIM Mean Y:0.9508695
x264 [info]: PSNR Mean Y:39.068 U:42.452 V:42.828 Avg:39.918 Global:39.157 kb/s:602.16
[12:46:24] thread b06ff000 joined ("reader")
[12:46:24] thread b0781000 exited ("muxer")
[12:46:24] thread b0781000 joined ("muxer")
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] fifo_close: trashing 0 buffer(s)
[12:46:24] Freed 1 buffers of size 512
[12:46:24] Freed 0 buffers of size 1024
[12:46:24] Freed 50 buffers of size 2048
[12:46:24] Freed 0 buffers of size 4096
[12:46:24] Freed 0 buffers of size 8192
[12:46:24] Freed 0 buffers of size 16384
[12:46:24] Freed 0 buffers of size 32768
[12:46:24] Freed 34 buffers of size 622080
[12:46:24] Allocated 21253632 bytes of buffers on this pass and Freed 21253632 bytes, 0 bytes leaked
[12:46:24] starting job
[12:46:24] + device /dev/disk2
[12:46:24] + title 1, chapter(s) 1 to 11
[12:46:24] + 720x576 -> 704x544, crop 0/0/2/12
[12:46:24] + filters
[12:46:24] + Deinterlace (ffmpeg or yadif/mcdeint) (-1)
[12:46:24] + video frame rate: 25.000 fps
[12:46:24] + video bitrate 600 kbps, pass 2
[12:46:24] + PixelRatio: 0, width:704, height: 544
[12:46:24] + encoder x264
[12:46:24] + x264 options: ref=2:bframes=2:subq=5:me=umh
[12:46:24] + audio AC3 passthrough
[12:46:24] + 80bd, Polish (AC3) (2.0 ch)
[12:46:24] thread b05d9000 started ("reader")
[12:46:24] + output: /Users/piotro/Desktop/Rodzina Pytalskich.avi
[12:46:24] thread b06ff000 started ("muxer")
[12:46:24] thread b0781000 started ("MPEG-2 decoder (libmpeg2)")
[12:46:24] thread b0939000 started ("Renderer")
[12:46:24] encx264: opening libx264 (pass 2)
x264 [info]: using cpu capabilities: MMX MMXEXT SSE SSE2 SSE3 SSSE3 Cache64
[12:46:25] dvd: Beginning of Cell (0) at block 0
[12:46:27] thread b09bb000 started ("H.264/AVC encoder (libx264)")
No accelerated IMDCT transform found
[12:46:27] thread b0a3d000 started ("AC3 decoder")
[12:46:27] sync: expecting 63459 video frames
[12:46:27] sync: first pts is 25854
[12:46:27] muxavi: opening /Users/piotro/Desktop/Rodzina Pytalskich.avi
[12:46:27] macgui: Using preset: PiotrO

[jbrjake]

x264-in-avi is broken in 0.9.2.

[golias]

Any plans in place to un-break it?

Because of all the Quicktime 5.1 audio problems with m4v files, AVI has been my wrapper of choice for my video files. It works great with Quicktime and Perian.

[TedJ]

It has been unbroken in SVN. Have you tried the most recent development snapshot?