Pts errors when I enable 8x8dct

[JohnAStebbins]

Anyone else seen this.
If I enable 8x8dct, mplayer and vlc complain about pts problems and I get lots of stutter in video and audio.
The video source is a tv series on DVD "Space above and beyond". Is mixed film and video rates.
This is the command

Code: Select all

./HandBrakeCLI -i /dev/dvd -t 1 -c 1 -o test.mkv -e x264 -q .62 -9 -5 -E ac3 -x ref=3:mixed-refs=1:subme=6:bframes=3:weightb=1:brdo=1:bime=1:b-pyramid=1:no-fast-pskip=1:no-dct-decimate=1:trellis=0:me=umh:analyse=all:8x8dct=1:merange=16 -V -v

This works without the pts problems

Code: Select all

./HandBrakeCLI -i /dev/dvd -t 1 -c 1 -o test.mkv -e x264 -q .62 -9 -5 -E ac3 -x ref=3:mixed-refs=1:subme=6:bframes=3:weightb=1:brdo=1:bime=1:b-pyramid=1:no-fast-pskip=1:no-dct-decimate=1:trellis=0:me=umh:analyse=some:merange=16 -V -v

Activity log

Code: Select all

[16:20:19] hb_init: checking cpu count
[16:20:19] hb_init: starting libhb thread
[16:20:19] thread 419d950 started ("libhb")
[16:20:22] hb_scan: path=/dev/sr0, title_index=0
[16:20:22] thread 41ea3950 started ("scan")
[16:20:22] scan: trying to open with libdvdread
[16:20:22] scan: DVD has 3 title(s)
[16:20:22] scan: scanning title 1
[16:20:22] scan: opening IFO for VTS 1
[16:20:22] pgc_id: 1, pgn: 1: pgc: 0x452f8c0
[16:20:22] scan: vts=1, ttn=1, cells=0->12, blocks=0->1060609, 1060610 blocks
[16:20:22] scan: duration is 00:45:35 (2735076 ms)
[16:20:22] scan: checking audio 1
[16:20:22] scan: id=80bd, lang=English (AC3), 3cc=eng
[16:20:22] scan: checking audio 2
[16:20:22] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[16:20:22] scan: checking audio 3
[16:20:22] scan: id=82bd, lang=Francais (AC3), 3cc=fra
[16:20:22] scan: checking subtitle 1
[16:20:22] scan: id=20bd, lang=English, 3cc=eng
[16:20:22] scan: checking subtitle 2
[16:20:22] scan: id=21bd, lang=Espanol, 3cc=spa
[16:20:22] scan: checking subtitle 3
[16:20:22] scan: id=22bd, lang=Espanol, 3cc=spa
[16:20:22] scan: checking subtitle 4
[16:20:22] scan: id=23bd, lang=Francais, 3cc=fra
[16:20:22] scan: title 1 has 13 chapters
[16:20:22] scan: chap 1 c=0->0, b=0->65262 (65263), 161362 ms
[16:20:22] scan: chap 2 c=1->1, b=65263->120837 (55575), 142334 ms
[16:20:22] scan: chap 3 c=2->2, b=120838->226345 (105508), 293387 ms
[16:20:22] scan: chap 4 c=3->3, b=226346->298221 (71876), 165342 ms
[16:20:22] scan: chap 5 c=4->4, b=298222->407078 (108857), 320369 ms
[16:20:22] scan: chap 6 c=5->5, b=407079->457337 (50259), 160452 ms
[16:20:22] scan: chap 7 c=6->6, b=457338->573746 (116409), 296556 ms
[16:20:22] scan: chap 8 c=7->7, b=573747->647193 (73447), 157315 ms
[16:20:22] scan: chap 9 c=8->8, b=647194->703464 (56271), 136295 ms
[16:20:22] scan: chap 10 c=9->9, b=703465->811199 (107735), 290451 ms
[16:20:22] scan: chap 11 c=10->10, b=811200->979188 (167989), 376489 ms
[16:20:22] scan: chap 12 c=11->11, b=979189->1060480 (81292), 234442 ms
[16:20:22] scan: chap 13 c=12->12, b=1060481->1060609 (129), 276 ms
[16:20:22] scan: aspect = 12
[16:20:22] scan: scanning title 2
[16:20:22] scan: opening IFO for VTS 1
[16:20:22] pgc_id: 2, pgn: 1: pgc: 0x454e970
[16:20:22] scan: vts=1, ttn=2, cells=0->12, blocks=1060610->2127505, 1066896 blocks
[16:20:22] scan: duration is 00:45:38 (2738276 ms)
[16:20:22] scan: checking audio 1
[16:20:22] scan: id=80bd, lang=English (AC3), 3cc=eng
[16:20:22] scan: checking audio 2
[16:20:22] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[16:20:22] scan: checking audio 3
[16:20:22] scan: id=82bd, lang=Francais (AC3), 3cc=fra
[16:20:22] scan: checking subtitle 1
[16:20:22] scan: id=20bd, lang=English, 3cc=eng
[16:20:22] scan: checking subtitle 2
[16:20:22] scan: id=21bd, lang=Espanol, 3cc=spa
[16:20:22] scan: checking subtitle 3
[16:20:22] scan: id=22bd, lang=Espanol, 3cc=spa
[16:20:22] scan: checking subtitle 4
[16:20:22] scan: id=23bd, lang=Francais, 3cc=fra
[16:20:22] scan: title 2 has 13 chapters
[16:20:22] scan: chap 1 c=0->0, b=1060610->1123718 (63109), 142300 ms
[16:20:22] scan: chap 2 c=1->1, b=1123719->1219402 (95684), 236333 ms
[16:20:22] scan: chap 3 c=2->2, b=1219403->1322949 (103547), 256308 ms
[16:20:22] scan: chap 4 c=3->3, b=1322950->1372058 (49109), 132157 ms
[16:20:22] scan: chap 5 c=4->4, b=1372059->1428268 (56210), 143335 ms
[16:20:22] scan: chap 6 c=5->5, b=1428269->1511499 (83231), 281319 ms
[16:20:22] scan: chap 7 c=6->6, b=1511500->1616031 (104532), 244251 ms
[16:20:22] scan: chap 8 c=7->7, b=1616032->1742361 (126330), 364397 ms
[16:20:22] scan: chap 9 c=8->8, b=1742362->1779560 (37199), 100294 ms
[16:20:22] scan: chap 10 c=9->9, b=1779561->1878520 (98960), 250312 ms
[16:20:22] scan: chap 11 c=10->10, b=1878521->1980440 (101920), 239459 ms
[16:20:22] scan: chap 12 c=11->11, b=1980441->2127357 (146917), 347594 ms
[16:20:22] scan: chap 13 c=12->12, b=2127358->2127505 (148), 210 ms
[16:20:22] scan: aspect = 12
[16:20:22] scan: scanning title 3
[16:20:22] scan: opening IFO for VTS 2
[16:20:22] pgc_id: 1, pgn: 1: pgc: 0x4559110
[16:20:22] scan: vts=2, ttn=1, cells=0->1, blocks=0->2814, 2815 blocks
[16:20:22] scan: duration is 00:00:09 (9000 ms)
[16:20:22] scan: ignoring title (too short)
[16:20:22] scan: decoding previews for title 1
[16:20:22] scan: preview 1
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: preview 2
[16:20:22] scan: preview 3
[16:20:22] Interlacing detected in preview frame 2
[16:20:22] scan: preview 4
[16:20:22] scan: preview 5
[16:20:22] scan: preview 6
[16:20:22] scan: preview 7
[16:20:22] scan: preview 8
[16:20:22] Interlacing detected in preview frame 7
[16:20:22] scan: preview 9
[16:20:22] Interlacing detected in preview frame 8
[16:20:22] scan: preview 10
[16:20:22] Interlacing detected in preview frame 9
[16:20:22] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/2/2, aspect 4:3
[16:20:22] scan: decoding previews for title 2
[16:20:22] scan: preview 1
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: AC3, rate=48000Hz, bitrate=192000
[16:20:22] scan: preview 2
[16:20:22] Interlacing detected in preview frame 1
[16:20:22] scan: preview 3
[16:20:22] scan: preview 4
[16:20:22] scan: preview 5
[16:20:22] scan: preview 6
[16:20:22] scan: preview 7
[16:20:22] Interlacing detected in preview frame 6
[16:20:22] scan: preview 8
[16:20:22] scan: preview 9
[16:20:22] scan: preview 10
[16:20:22] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/0/2/2, aspect 4:3
[16:20:22] scan: title (0) job->width:640, job->height:480
[16:20:22] scan: title (1) job->width:640, job->height:480
[16:20:22] thread 41ea3950 exited ("scan")
[16:20:22] thread 41ea3950 joined ("scan")
[16:20:22] libhb: scan thread found 2 valid title(s)
[16:20:49] thread 41ea3950 started ("work")
[16:20:49] 1 job(s) to process
[16:20:49] starting job
[16:20:49]  + device /dev/sr0
[16:20:49]  + title 2, chapter(s) 1 to 1
[16:20:49]  + 720x480 -> 720x480, crop 0/0/2/2
[16:20:49]  + filters
[16:20:49]    + Detelecine (pullup) (default settings)
[16:20:49]    + Decombs selectively with (ffmpeg or yadif/mcdeint or blending) (default settings)
[16:20:49]    + Denoise (hqdn3d) (2:1:2:3)
[16:20:49]  + video frame rate: 29.970 fps -> variable fps
[16:20:49]  + video quality 0.62
[16:20:49]  + PixelRatio: 2, width:720, height: 480
[16:20:49]  + encoder x264
[16:20:49]    + x264 options: ref=3:mixed-refs=1:subme=6:bframes=3:weightb=1:brdo=1:bime=1:b-pyramid=1:no-fast-pskip=1:no-dct-decimate=1:trellis=0:me=umh:analyse=all:8x8dct=1:merange=16
[16:20:49]  + subtitle 20bd, English
[16:20:49]  + audio track 0
[16:20:49]    + input track 0
[16:20:49]    + AC3 passthrough
[16:20:49]      + 80bd, English (AC3) (Dolby Surround)
[16:20:49] thread 40d00950 started ("reader")
[16:20:49]  + output: /home/jstebbins/Media/JohnsNewRips/Space Above and Beyond/SPACE ABOVE AND BEYOND.mkv
[16:20:49] thread 428a4950 started ("muxer")
[16:20:49] thread 432a5950 started ("MPEG-2 decoder (libmpeg2)")
[16:20:49] thread 43ca6950 started ("Renderer")
[16:20:49] encx264: keyint-min: 30, keyint-max: 300
[16:20:49] encx264: encoding with stored aspect 53/60
[16:20:49] encx264: Encoding at constant RF 19.379999
[16:20:49] encx264: opening libx264 (pass 0)
x264 [info]: using SAR=53/60
x264 [info]: using cpu capabilities: MMX MMX2 SSE SSE2 SSE3 SSSE3 Cache64
[16:20:49] thread 446a7950 started ("H.264/AVC encoder (libx264)")
[16:20:49] thread 450a8950 started ("Subtitle decoder")
No accelerated IMDCT transform found
[16:20:49] thread 45aa9950 started ("AC3 decoder")
[16:20:49] sync: expecting 4294 video frames
[16:20:51] reader: first SCR 146 scr_offset 25257
[16:20:51] mpeg2: "" (1) at frame 0 time 0
[16:20:51] 1.001000s: Video -> Film
[16:20:51] 3.536867s: Film -> Video
[16:21:49] reader: end of chapter 1 (media 1) reached at media chapter 2
[16:21:49] reader: done. 0 scr changes
[16:21:49] thread 40d00950 exited ("reader")
[16:21:54] sync: got 4262 frames, 4294 expected
[16:21:54] thread 450a8950 exited ("Subtitle decoder")
[16:21:54] thread 45aa9950 exited ("AC3 decoder")
[16:21:54] thread 43ca6950 exited ("Renderer")
[16:21:54] thread 446a7950 exited ("H.264/AVC encoder (libx264)")
[16:21:54] thread 432a5950 exited ("MPEG-2 decoder (libmpeg2)")
[16:21:54] thread 432a5950 joined ("MPEG-2 decoder (libmpeg2)")
[16:21:54] mpeg2 done: 4263 frames
[16:21:54] thread 43ca6950 joined ("Renderer")
[16:21:54] render: lost time: 2315313 (771 frames)
[16:21:54] render: gained time: 2315313 (3046 frames) (0 not accounted for)
[16:21:54] render: average dropped frame duration: 3003
[16:21:54] fifo_close: trashing 1 buffer(s)
[16:21:54] fifo_close: trashing 2 buffer(s)
[16:21:54] thread 446a7950 joined ("H.264/AVC encoder (libx264)")
x264 [info]: slice I:47    Avg QP:15.12  size: 24163  PSNR Mean Y:49.09 U:49.90 V:50.94 Avg:49.40 Global:48.55
x264 [info]: slice P:1900  Avg QP:16.88  size:  9809  PSNR Mean Y:47.50 U:49.59 V:50.57 Avg:48.12 Global:47.24
x264 [info]: slice B:1541  Avg QP:21.37  size:  2437  PSNR Mean Y:46.12 U:48.54 V:49.47 Avg:46.75 Global:45.55
x264 [info]: mb I  I16..4: 39.8% 43.1% 17.1%
x264 [info]: mb P  I16..4: 10.6% 16.1%  5.1%  P16..4: 46.1% 12.6%  3.0%  0.2%  0.1%    skip: 6.3%
x264 [info]: mb B  I16..4:  0.4%  0.9%  0.2%  B16..8: 36.7%  1.5%  3.2%  direct: 1.6%  skip:55.5%
x264 [info]: 8x8 transform  intra:50.6%  inter:41.2%
x264 [info]: ref P  75.8% 15.4%  8.8%
x264 [info]: ref B  90.0% 10.0%
x264 [info]: SSIM Mean Y:0.9863699
x264 [info]: PSNR Mean Y:46.912 U:49.128 V:50.091 Avg:47.533 Global:46.426 kb/s:1617.28
[16:21:54] thread 450a8950 joined ("Subtitle decoder")
[16:21:54] thread 45aa9950 joined ("AC3 decoder")
[16:21:54] thread 40d00950 joined ("reader")
[16:21:55] mux: file size, 27033485 bytes
[16:21:55] mux: track 0, 23528622 bytes, 1324.85 kbps
[16:21:55] mux: track 1, 3409152 bytes, 191.96 kbps
[16:21:55] mux: overhead, 12.07 bytes per frame
[16:21:55] thread 428a4950 exited ("muxer")
[16:21:55] thread 428a4950 joined ("muxer")
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 1 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)
[16:21:55] fifo_close: trashing 0 buffer(s)

[JohnAStebbins]

Just an FYI for anyone that's interested in this. I'm slowly making progress on tracking down the cause of this problem.

So far I've determined that mplayer is getting bad sequence parameters from the stream. In particular, the num_reorder_frames field is being read as 0. This makes mplayer think there are no b-frames and consequently think that pts has gone backwards when it encounters a b-frame.

I haven't yet figured which side the error is in (x264 encoder or ffmpeg decoder). The values look good on the x264 end prior to being encoded into the stream. Either they are being stepped on at some point, or x264 and ffmpeg don't agree on the way some field is encoded.