Missing Frames in HB encodes

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

*******************************
Post Reply
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Missing Frames in HB encodes

Post by sdm »

On season 6 of the Sopranos, I've found multiple missing frames in every HB encode. I'm not saying 'dropped frames' during playback but 'missing' altogether.

See the following sequence:
Image
There is supposed to be a frame between these two.

The frame is not missing if I only encode 1 chapter.
Image
There should be three frames like this.

I've encoded from:
1. the DVD directly
2. from MTR 3.0 r14f (full disc extraction and others)
3. from Dvd2OneX output of MTR fileset

The source is ntsc film.

I've used several HB 9.0 and HB 9.1 presets:
1. Apple TV
2. QuickTime
3. Normal
4. Custom - varying nearly every advanced x264 option
5. Classic

When I chose Classic preset with the ffmpeg encoder, I realised it wasn't an x264 option causing a playback problem. I scrubbed through the video, and realised the encodes are actually missing frames!


I also tried an encode chapters markers off.

As I said above, the missing frame issue doesn't occur if I only encode a single chapter.

I've tried to be thorough to find a way to fix the problem, but now I've run out of ideas and am pretty sure this is a bug.

If anyone can help, or has any ideas about this, let me know.
As you can tell I really want to encode these episodes with HandBrake, so let me know if you need more info from me to analyse the problem.

Thanks,
--sdm.
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Post by sdm »

Just tested. Same problem using MediaFork 0.8b1 on a intel mac mini and HandBrake 0.7.1 on a ppc G5.

--sdm.
Top
User avatar
Ritsuka
HandBrake Team
Posts: 1655
Joined: Fri Jan 12, 2007 11:29 am

Post by Ritsuka »

Do you have perian installed?
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Post by sdm »

Yes, I do. The problem also presents in VLC.
Do you have an idea?

Thanks,
--sdm.
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Post by sdm »

Here is an update.

Used Fairmount to copy Video_TS, looked for zero cells using dtox but none were found.

Ran HB on using classic settings. Same result - missing (not dropped in playback) frames.

Tried Visual Hub on same Video_TS using its default settings - no frames missing (also no chapters, surround audio etc.)


Thanks to the devs for their efforts, hopefully one of them will read this and respond.

--sdm.
Top
jbrjake
Veteran User
Posts: 4805
Joined: Wed Dec 13, 2006 1:38 am

Post by jbrjake »

Like with every issue, verbose activity logs are a must if you expect help. Otherwise there's no way of ruling out an AV de-sync causing a trashed video frame.
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Post by sdm »

Hi Jbrjake,

I knew you'd take a look!
I hope this is what you are after.

Thanks,
-sdm.

[06:22:14] hb_init: checking for updates
[06:22:14] thread 1831e00 started ("update")
[06:22:14] latest stable: 0.9.1, build 2007100800
[06:22:14] latest unstable: 0.9.1, build 2007100800
[06:22:14] thread 1831e00 exited ("update")
[06:22:14] thread 1831e00 joined ("update")
[06:22:14] hb_init: checking cpu count
[06:22:14] hb_init: starting libhb thread
[06:22:14] thread 1831e00 started ("libhb")
[06:22:43] hb_scan: path=/Volumes/Jive Turkey/Video Temp/Television Shows/Sopranos s6d3/VIDEO_TS, title_index=0
[06:22:43] thread 1870000 started ("scan")
[06:22:43] scan: trying to open with libdvdread
[06:22:43] scan: DVD has 9 title(s)
[06:22:43] scan: scanning title 1
[06:22:43] scan: opening IFO for VTS 1
[06:22:43] pgc_id: 1, pgn: 1: pgc: 0x6751790
[06:22:43] scan: vts=1, ttn=1, cells=0->8, blocks=0->3637003, 1256950 blocks
[06:22:43] scan: duration is 00:54:50 (3290233 ms)
[06:22:43] scan: checking audio 1
[06:22:43] scan: id=80bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: checking audio 2
[06:22:43] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[06:22:43] scan: checking audio 3
[06:22:43] scan: id=82bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: checking subtitle 1
[06:22:43] scan: id=20bd, lang=English, 3cc=eng
[06:22:43] scan: checking subtitle 2
[06:22:43] scan: id=21bd, lang=Francais, 3cc=fre
[06:22:43] scan: checking subtitle 3
[06:22:43] scan: id=22bd, lang=Espanol, 3cc=spa
[06:22:43] scan: checking subtitle 4
[06:22:43] scan: id=23bd, lang=English, 3cc=eng
[06:22:43] scan: title 1 has 9 chapters
[06:22:43] scan: chapter 9 too short (4 blocks, cells=8->8), merging
[06:22:43] scan: chap 1 c=0->0, b=0->48926 (48927), 98307 ms
[06:22:43] scan: chap 2 c=1->1, b=48927->174342 (125416), 331459 ms
[06:22:43] scan: chap 3 c=2->2, b=174343->276032 (101690), 274553 ms
[06:22:43] scan: chap 4 c=3->3, b=276033->523592 (247560), 648584 ms
[06:22:43] scan: chap 5 c=4->4, b=523593->823554 (299962), 805833 ms
[06:22:43] scan: chap 6 c=5->5, b=823555->947879 (124325), 332394 ms
[06:22:43] scan: chap 7 c=6->6, b=947880->1207463 (259584), 661751 ms
[06:22:43] scan: chap 8 c=7->8, b=1207464->3637003 (49486), 137350 ms
[06:22:43] scan: aspect = 16
[06:22:43] scan: scanning title 2
[06:22:43] scan: opening IFO for VTS 1
[06:22:43] pgc_id: 2, pgn: 1: pgc: 0x6751790
[06:22:43] scan: vts=1, ttn=2, cells=0->9, blocks=1256946->3637003, 1170911 blocks
[06:22:43] scan: duration is 00:54:03 (3243233 ms)
[06:22:43] scan: checking audio 1
[06:22:43] scan: id=80bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: checking audio 2
[06:22:43] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[06:22:43] scan: checking audio 3
[06:22:43] scan: audio channel is not active
[06:22:43] scan: checking subtitle 1
[06:22:43] scan: id=20bd, lang=English, 3cc=eng
[06:22:43] scan: checking subtitle 2
[06:22:43] scan: id=21bd, lang=Francais, 3cc=fre
[06:22:43] scan: checking subtitle 3
[06:22:43] scan: id=22bd, lang=Espanol, 3cc=spa
[06:22:43] scan: checking subtitle 4
[06:22:43] scan: id=23bd, lang=English, 3cc=eng
[06:22:43] scan: title 2 has 9 chapters
[06:22:43] scan: chapter 9 too short (4 blocks, cells=9->9), merging
[06:22:43] scan: chap 1 c=0->0, b=1256946->1304796 (47851), 98321 ms
[06:22:43] scan: chap 2 c=1->1, b=1304797->1470764 (165968), 423690 ms
[06:22:43] scan: chap 3 c=2->2, b=1470765->1600707 (129943), 388524 ms
[06:22:43] scan: chap 4 c=3->4, b=1600708->1808961 (208254), 616729 ms
[06:22:43] scan: chap 5 c=5->5, b=1808962->1922551 (113590), 312300 ms
[06:22:43] scan: chap 6 c=6->6, b=1922552->2187349 (264798), 761694 ms
[06:22:43] scan: chap 7 c=7->7, b=2187350->2390318 (202969), 530652 ms
[06:22:43] scan: chap 8 c=8->9, b=2390319->3637003 (37538), 111318 ms
[06:22:43] scan: aspect = 16
[06:22:43] scan: scanning title 3
[06:22:43] scan: opening IFO for VTS 1
[06:22:43] pgc_id: 3, pgn: 1: pgc: 0x6751790
[06:22:43] scan: vts=1, ttn=3, cells=0->8, blocks=2427853->3637003, 1209151 blocks
[06:22:43] scan: duration is 00:54:00 (3240233 ms)
[06:22:43] scan: checking audio 1
[06:22:43] scan: id=80bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: checking audio 2
[06:22:43] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[06:22:43] scan: checking audio 3
[06:22:43] scan: id=82bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: checking subtitle 1
[06:22:43] scan: id=20bd, lang=English, 3cc=eng
[06:22:43] scan: checking subtitle 2
[06:22:43] scan: id=21bd, lang=Francais, 3cc=fre
[06:22:43] scan: checking subtitle 3
[06:22:43] scan: id=22bd, lang=Espanol, 3cc=spa
[06:22:43] scan: checking subtitle 4
[06:22:43] scan: id=23bd, lang=English, 3cc=eng
[06:22:43] scan: title 3 has 9 chapters
[06:22:43] scan: chapter 9 too short (4 blocks, cells=8->8), merging
[06:22:43] scan: chap 1 c=0->0, b=2427853->2477093 (49241), 98208 ms
[06:22:43] scan: chap 2 c=1->1, b=2477094->2627932 (150839), 437536 ms
[06:22:43] scan: chap 3 c=2->2, b=2627933->2853797 (225865), 623642 ms
[06:22:43] scan: chap 4 c=3->3, b=2853798->3085444 (231647), 626811 ms
[06:22:43] scan: chap 5 c=4->4, b=3085445->3166168 (80724), 229228 ms
[06:22:43] scan: chap 6 c=5->5, b=3166169->3466197 (300029), 779788 ms
[06:22:43] scan: chap 7 c=6->6, b=3466198->3600483 (134286), 339524 ms
[06:22:43] scan: chap 8 c=7->8, b=3600484->3637003 (36520), 105491 ms
[06:22:43] scan: aspect = 16
[06:22:43] scan: scanning title 4
[06:22:43] scan: opening IFO for VTS 1
[06:22:43] pgc_id: 4, pgn: 1: pgc: 0x67535c0
[06:22:43] scan: vts=1, ttn=4, cells=0->0, blocks=3637000->3637003, 4 blocks
[06:22:43] scan: title too short (4 blocks), ignoring
[06:22:43] scan: scanning title 5
[06:22:43] scan: opening IFO for VTS 2
[06:22:43] pgc_id: 1, pgn: 1: pgc: 0x67535c0
[06:22:43] scan: vts=2, ttn=1, cells=0->0, blocks=0->3, 4 blocks
[06:22:43] scan: title too short (4 blocks), ignoring
[06:22:43] scan: scanning title 6
[06:22:43] scan: opening IFO for VTS 2
[06:22:43] pgc_id: 2, pgn: 1: pgc: 0x6751790
[06:22:43] scan: vts=2, ttn=2, cells=0->1, blocks=4->5697, 5694 blocks
[06:22:43] scan: duration is 00:00:20 (20033 ms)
[06:22:43] scan: checking audio 1
[06:22:43] scan: id=80bd, lang=English (AC3), 3cc=eng
[06:22:43] scan: title 6 has 2 chapters
[06:22:43] scan: chap 1 c=0->0, b=4->69 (66), 5000 ms
[06:22:43] scan: chap 2 c=1->1, b=70->5697 (5628), 15033 ms
[06:22:43] scan: aspect = 16
[06:22:43] scan: scanning title 7
[06:22:43] scan: opening IFO for VTS 2
[06:22:43] pgc_id: 3, pgn: 1: pgc: 0x6751790
[06:22:43] scan: vts=2, ttn=3, cells=0->0, blocks=0->3, 4 blocks
[06:22:43] scan: title too short (4 blocks), ignoring
[06:22:43] scan: scanning title 8
[06:22:43] scan: opening IFO for VTS 2
[06:22:43] pgc_id: 4, pgn: 1: pgc: 0x67535c0
[06:22:43] scan: vts=2, ttn=4, cells=0->0, blocks=0->3, 4 blocks
[06:22:43] scan: title too short (4 blocks), ignoring
[06:22:43] scan: scanning title 9
[06:22:43] scan: opening IFO for VTS 2
[06:22:43] pgc_id: 5, pgn: 1: pgc: 0x6702fa0
[06:22:43] scan: vts=2, ttn=5, cells=0->0, blocks=0->3, 4 blocks
[06:22:43] scan: title too short (4 blocks), ignoring
[06:22:43] scan: decoding previews for title 1
[06:22:44] scan: preview 1
[06:22:44] scan: AC3, rate=48000Hz, bitrate=448000
[06:22:44] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:44] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:44] scan: preview 2
[06:22:44] scan: preview 3
[06:22:44] scan: preview 4
[06:22:44] scan: preview 5
[06:22:44] scan: preview 6
[06:22:44] Title's mostly progressive NTSC, setting fps to 23.976
[06:22:44] scan: preview 7
[06:22:44] scan: preview 8
[06:22:44] scan: preview 9
[06:22:44] scan: preview 10
[06:22:45] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[06:22:45] scan: decoding previews for title 2
[06:22:45] scan: preview 1
[06:22:45] scan: AC3, rate=48000Hz, bitrate=448000
[06:22:45] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:45] scan: preview 2
[06:22:45] scan: preview 3
[06:22:45] scan: preview 4
[06:22:45] scan: preview 5
[06:22:45] scan: preview 6
[06:22:45] Title's mostly progressive NTSC, setting fps to 23.976
[06:22:45] scan: preview 7
[06:22:45] scan: preview 8
[06:22:45] scan: preview 9
[06:22:45] scan: preview 10
[06:22:45] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[06:22:45] scan: decoding previews for title 3
[06:22:45] scan: preview 1
[06:22:45] scan: AC3, rate=48000Hz, bitrate=448000
[06:22:45] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:45] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:45] scan: preview 2
[06:22:46] scan: preview 3
[06:22:46] scan: preview 4
[06:22:46] scan: preview 5
[06:22:46] scan: preview 6
[06:22:46] Title's mostly progressive NTSC, setting fps to 23.976
[06:22:46] scan: preview 7
[06:22:46] scan: preview 8
[06:22:46] scan: preview 9
[06:22:46] scan: preview 10
[06:22:46] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[06:22:46] scan: decoding previews for title 6
[06:22:46] scan: preview 1
[06:22:46] scan: AC3, rate=48000Hz, bitrate=192000
[06:22:46] scan: preview 2
[06:22:46] 3.984333s: Progressive -> Interlaced
[06:22:46] scan: preview 3
[06:22:46] 5.302311s: Interlaced -> Progressive
[06:22:46] scan: preview 4
[06:22:46] scan: preview 5
[06:22:46] scan: preview 6
[06:22:47] scan: preview 7
[06:22:47] scan: preview 8
[06:22:47] scan: preview 9
[06:22:47] scan: preview 10
[06:22:47] scan: 720x480, 29.970 fps, autocrop = 0/0/0/0
[06:22:47] scan: title (0) job->width:720, job->height:400
[06:22:47] scan: title (1) job->width:720, job->height:400
[06:22:47] scan: title (2) job->width:720, job->height:400
[06:22:47] scan: title (3) job->width:720, job->height:400
[06:22:47] thread 1870000 exited ("scan")
[06:22:47] thread 1870000 joined ("scan")
[06:22:47] libhb: scan thread found 4 valid title(s)
[06:23:36] thread 1915600 started ("work")
[06:23:36] 1 job(s) to process
[06:23:36] starting job
[06:23:36] + device /Volumes/Jive Turkey/Video Temp/Television Shows/Sopranos s6d3/VIDEO_TS
[06:23:36] + title 1, chapter(s) 1 to 3
[06:23:36] + 720x480 -> 720x400, crop 0/0/0/0
[06:23:36] + grayscale off
[06:23:36] + filters
[06:23:36] + 23.976 fps, video bitrate 1000 kbps, pass 0
[06:23:36] + PixelRatio: 0, width:720, height: 400
[06:23:36] + encoder FFmpeg
[06:23:36] + audio 160 kbps, 48000 Hz
[06:23:36] + encoder faac
[06:23:36] + 80bd, English (AC3) (5.1 ch)
[06:23:36] + Requested mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[06:23:36] + Actual mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[06:23:36] thread 1839200 started ("reader")
[06:23:36] + output: /Volumes/Jive Turkey/Video Temp/Television Shows/Sopranos s6d3/THE_SOPRANOS 6-1 chap1-3.m4v
[06:23:36] thread 18ea400 started ("muxer")
[06:23:36] thread 18ea800 started ("MPEG-2 decoder (libmpeg2)")
[06:23:36] thread 192e000 started ("Renderer")
[mpeg4 @ 0x3aa7ec]removing common factors from framerate
[06:23:36] thread 1978400 started ("MPEG-4 encoder (libavcodec)")
No accelerated IMDCT transform found
[06:23:36] thread 1979800 started ("AC3 decoder")
[06:23:36] thread 198ac00 started ("AAC encoder (libfaac)")
[06:23:36] sync: expecting 16910 video frames
[06:23:36] DVD: Beginning of Cell (0) at block 0
[06:23:36] sync: first pts is 31263
[06:23:38] 5.802811s: Progressive -> Interlaced
[06:23:38] 6.253267s: Interlaced -> Progressive
[06:24:20] DVD: End of Cell (0) at block 48897
[06:24:20] DVD: Beginning of Cell (1) at block 48927
[06:24:20] DVD: Chapter Break Cell Found
[06:24:24] MPEG2: Chapter Break Cell Found, searching for GOP
[06:24:24] MPEG2: Group of pictures found, searching for I-Frame
[06:24:24] MPEG2: I-Frame Found
[06:24:24] MPEG2: Chapter Break Inserted
[06:24:25] WORK: Copying Chapter Break
[06:24:25] WORK: Copying Chapter Break
[06:26:57] DVD: End of Cell (1) at block 173928
[06:26:57] DVD: Beginning of Cell (2) at block 174343
[06:26:57] DVD: Chapter Break Cell Found
[06:27:01] MPEG2: Chapter Break Cell Found, searching for GOP
[06:27:01] MPEG2: Group of pictures found, searching for I-Frame
[06:27:01] MPEG2: I-Frame Found
[06:27:01] MPEG2: Chapter Break Inserted
[06:27:02] WORK: Copying Chapter Break
[06:27:02] WORK: Copying Chapter Break
[06:29:03] DVD: End of Cell (2) at block 275877
[06:29:03] DVD: Beginning of Cell (3) at block 276033
[06:29:03] DVD: Chapter Break Cell Found
[06:29:03] reader: end of chapter 3 reached (4)
[06:29:03] reader: done
[06:29:03] thread 1839200 exited ("reader")
[06:29:07] sync: got 16908 frames, 16910 expected
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
[06:29:08] thread 1979800 exited ("AC3 decoder")
[06:29:08] thread 192e000 exited ("Renderer")
[06:29:08] thread 18ea800 exited ("MPEG-2 decoder (libmpeg2)")
[06:29:08] thread 18ea800 joined ("MPEG-2 decoder (libmpeg2)")
[06:29:08] thread 192e000 joined ("Renderer")
[06:29:08] fifo_close: trashing 0 buffer(s)
[06:29:08] thread 1978400 exited ("MPEG-4 encoder (libavcodec)")
[06:29:08] thread 1978400 joined ("MPEG-4 encoder (libavcodec)")
[06:29:08] encavcodec: closing libavcodec
[06:29:08] thread 1979800 joined ("AC3 decoder")
[06:29:08] thread 198ac00 exited ("AAC encoder (libfaac)")
[06:29:08] thread 198ac00 joined ("AAC encoder (libfaac)")
[06:29:08] thread 1839200 joined ("reader")
[06:29:10] mux: file size, 102765636 bytes
[06:29:10] mux: track 0, 88356447 bytes, 1002.34 kbps
[06:29:10] mux: video bitrate error, +205885 bytes
[06:29:10] mux: track 1, 14100412 bytes, 159.96 kbps
[06:29:10] mux: overhead, 6.18 bytes per frame
[06:29:10] thread 18ea400 exited ("muxer")
[06:29:10] thread 18ea400 joined ("muxer")
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 0 buffer(s)
[06:29:10] fifo_close: trashing 2 buffer(s)
[06:29:10] Freed 3 buffers of size 512
[06:29:10] Freed 5 buffers of size 1024
[06:29:10] Freed 2048 buffers of size 2048
[06:29:10] Freed 0 buffers of size 4096
[06:29:10] Freed 0 buffers of size 8192
[06:29:10] Freed 67 buffers of size 16384
[06:29:10] Freed 0 buffers of size 32768
[06:29:10] Freed 110 buffers of size 518400
[06:29:10] Allocated 62322688 bytes of buffers on this pass and Freed 62322688 bytes, 0 bytes leaked
[06:29:10] thread 1915600 exited ("work")
[06:29:10] thread 1915600 joined ("work")
[06:29:10] libhb: work result = 0
Top
jbrjake
Veteran User
Posts: 4805
Joined: Wed Dec 13, 2006 1:38 am

Post by jbrjake »

sdm wrote:[06:22:43] scan: title 1 has 9 chapters
[06:22:43] scan: chapter 9 too short (4 blocks, cells=8->8), merging
[06:22:43] scan: chap 1 c=0->0, b=0->48926 (48927), 98307 ms
[06:22:43] scan: chap 2 c=1->1, b=48927->174342 (125416), 331459 ms
[06:22:43] scan: chap 3 c=2->2, b=174343->276032 (101690), 274553 ms
[06:22:43] scan: chap 4 c=3->3, b=276033->523592 (247560), 648584 ms
[06:22:43] scan: chap 5 c=4->4, b=523593->823554 (299962), 805833 ms
[06:22:43] scan: chap 6 c=5->5, b=823555->947879 (124325), 332394 ms
[06:22:43] scan: chap 7 c=6->6, b=947880->1207463 (259584), 661751 ms
[06:22:43] scan: chap 8 c=7->8, b=1207464->3637003 (49486), 137350 ms

<snip>

[06:29:07] sync: got 16908 frames, 16910 expected
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
[06:29:08] Reader has exited early, inserting silence.
[06:29:08] sync: adding 50 ms of silence for track 80bd
Those strike me as possible problem areas -- that chapter merging causes all sorts of trouble, and it's something that's particular to HB so it'd explain why VH output differs.

I find it interesting that HB only thinks it's missing 2 frames. You say you're missing frames throughout?

Also...in VisualHub, what's the fps of the output, out of curiosity? 23.976 like it should be? And how do the durations of the video tracks compare?
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Post by sdm »

Hi jbrjake,

The activity log above was from an encode of the first 3 chapters, not the entire title. Below is the activity for an encode of all 8 chapters. Can you see more missing frames in this log?
Anyways, in the first 3 chapters there are more than 2 missing frames (maybe 10 or so). I'll count later - sorry.

The HB durations are 54:51.20 video and 54.:51.11 audio.

The VH durations are 54:53.37 video and 54:53.20 audio and the framerate is 23.976. The a/v sync seems good to me.

What do you think?

Thanks,
-sdm.

[10:56:15] hb_init: checking for updates
[10:56:15] thread 187e800 started ("update")
[10:56:15] latest stable: 0.9.1, build 2007100800
[10:56:15] latest unstable: 0.9.1, build 2007100800
[10:56:15] thread 187e800 exited ("update")
[10:56:15] thread 187e800 joined ("update")
[10:56:15] hb_init: checking cpu count
[10:56:15] hb_init: starting libhb thread
[10:56:15] thread 187e800 started ("libhb")
[10:56:24] hb_scan: path=/Volumes/Jive Turkey/Video Temp/Television Shows/Sopranos s6d3/VIDEO_TS, title_index=0
[10:56:24] thread 182fa00 started ("scan")
[10:56:24] scan: trying to open with libdvdread
[10:56:24] scan: DVD has 9 title(s)
[10:56:24] scan: scanning title 1
[10:56:24] scan: opening IFO for VTS 1
[10:56:24] pgc_id: 1, pgn: 1: pgc: 0x673e760
[10:56:24] scan: vts=1, ttn=1, cells=0->8, blocks=0->3637003, 1256950 blocks
[10:56:24] scan: duration is 00:54:50 (3290233 ms)
[10:56:24] scan: checking audio 1
[10:56:24] scan: id=80bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: checking audio 2
[10:56:24] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[10:56:24] scan: checking audio 3
[10:56:24] scan: id=82bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: checking subtitle 1
[10:56:24] scan: id=20bd, lang=English, 3cc=eng
[10:56:24] scan: checking subtitle 2
[10:56:24] scan: id=21bd, lang=Francais, 3cc=fre
[10:56:24] scan: checking subtitle 3
[10:56:24] scan: id=22bd, lang=Espanol, 3cc=spa
[10:56:24] scan: checking subtitle 4
[10:56:24] scan: id=23bd, lang=English, 3cc=eng
[10:56:24] scan: title 1 has 9 chapters
[10:56:24] scan: chapter 9 too short (4 blocks, cells=8->8), merging
[10:56:24] scan: chap 1 c=0->0, b=0->48926 (48927), 98307 ms
[10:56:24] scan: chap 2 c=1->1, b=48927->174342 (125416), 331459 ms
[10:56:24] scan: chap 3 c=2->2, b=174343->276032 (101690), 274553 ms
[10:56:24] scan: chap 4 c=3->3, b=276033->523592 (247560), 648584 ms
[10:56:24] scan: chap 5 c=4->4, b=523593->823554 (299962), 805833 ms
[10:56:24] scan: chap 6 c=5->5, b=823555->947879 (124325), 332394 ms
[10:56:24] scan: chap 7 c=6->6, b=947880->1207463 (259584), 661751 ms
[10:56:24] scan: chap 8 c=7->8, b=1207464->3637003 (49486), 137350 ms
[10:56:24] scan: aspect = 16
[10:56:24] scan: scanning title 2
[10:56:24] scan: opening IFO for VTS 1
[10:56:24] pgc_id: 2, pgn: 1: pgc: 0x673a630
[10:56:24] scan: vts=1, ttn=2, cells=0->9, blocks=1256946->3637003, 1170911 blocks
[10:56:24] scan: duration is 00:54:03 (3243233 ms)
[10:56:24] scan: checking audio 1
[10:56:24] scan: id=80bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: checking audio 2
[10:56:24] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[10:56:24] scan: checking audio 3
[10:56:24] scan: audio channel is not active
[10:56:24] scan: checking subtitle 1
[10:56:24] scan: id=20bd, lang=English, 3cc=eng
[10:56:24] scan: checking subtitle 2
[10:56:24] scan: id=21bd, lang=Francais, 3cc=fre
[10:56:24] scan: checking subtitle 3
[10:56:24] scan: id=22bd, lang=Espanol, 3cc=spa
[10:56:24] scan: checking subtitle 4
[10:56:24] scan: id=23bd, lang=English, 3cc=eng
[10:56:24] scan: title 2 has 9 chapters
[10:56:24] scan: chapter 9 too short (4 blocks, cells=9->9), merging
[10:56:24] scan: chap 1 c=0->0, b=1256946->1304796 (47851), 98321 ms
[10:56:24] scan: chap 2 c=1->1, b=1304797->1470764 (165968), 423690 ms
[10:56:24] scan: chap 3 c=2->2, b=1470765->1600707 (129943), 388524 ms
[10:56:24] scan: chap 4 c=3->4, b=1600708->1808961 (208254), 616729 ms
[10:56:24] scan: chap 5 c=5->5, b=1808962->1922551 (113590), 312300 ms
[10:56:24] scan: chap 6 c=6->6, b=1922552->2187349 (264798), 761694 ms
[10:56:24] scan: chap 7 c=7->7, b=2187350->2390318 (202969), 530652 ms
[10:56:24] scan: chap 8 c=8->9, b=2390319->3637003 (37538), 111318 ms
[10:56:24] scan: aspect = 16
[10:56:24] scan: scanning title 3
[10:56:24] scan: opening IFO for VTS 1
[10:56:24] pgc_id: 3, pgn: 1: pgc: 0x673ec60
[10:56:24] scan: vts=1, ttn=3, cells=0->8, blocks=2427853->3637003, 1209151 blocks
[10:56:24] scan: duration is 00:54:00 (3240233 ms)
[10:56:24] scan: checking audio 1
[10:56:24] scan: id=80bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: checking audio 2
[10:56:24] scan: id=81bd, lang=Espanol (AC3), 3cc=spa
[10:56:24] scan: checking audio 3
[10:56:24] scan: id=82bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: checking subtitle 1
[10:56:24] scan: id=20bd, lang=English, 3cc=eng
[10:56:24] scan: checking subtitle 2
[10:56:24] scan: id=21bd, lang=Francais, 3cc=fre
[10:56:24] scan: checking subtitle 3
[10:56:24] scan: id=22bd, lang=Espanol, 3cc=spa
[10:56:24] scan: checking subtitle 4
[10:56:24] scan: id=23bd, lang=English, 3cc=eng
[10:56:24] scan: title 3 has 9 chapters
[10:56:24] scan: chapter 9 too short (4 blocks, cells=8->8), merging
[10:56:24] scan: chap 1 c=0->0, b=2427853->2477093 (49241), 98208 ms
[10:56:24] scan: chap 2 c=1->1, b=2477094->2627932 (150839), 437536 ms
[10:56:24] scan: chap 3 c=2->2, b=2627933->2853797 (225865), 623642 ms
[10:56:24] scan: chap 4 c=3->3, b=2853798->3085444 (231647), 626811 ms
[10:56:24] scan: chap 5 c=4->4, b=3085445->3166168 (80724), 229228 ms
[10:56:24] scan: chap 6 c=5->5, b=3166169->3466197 (300029), 779788 ms
[10:56:24] scan: chap 7 c=6->6, b=3466198->3600483 (134286), 339524 ms
[10:56:24] scan: chap 8 c=7->8, b=3600484->3637003 (36520), 105491 ms
[10:56:24] scan: aspect = 16
[10:56:24] scan: scanning title 4
[10:56:24] scan: opening IFO for VTS 1
[10:56:24] pgc_id: 4, pgn: 1: pgc: 0x6740550
[10:56:24] scan: vts=1, ttn=4, cells=0->0, blocks=3637000->3637003, 4 blocks
[10:56:24] scan: title too short (4 blocks), ignoring
[10:56:24] scan: scanning title 5
[10:56:24] scan: opening IFO for VTS 2
[10:56:24] pgc_id: 1, pgn: 1: pgc: 0x6751cc0
[10:56:24] scan: vts=2, ttn=1, cells=0->0, blocks=0->3, 4 blocks
[10:56:24] scan: title too short (4 blocks), ignoring
[10:56:24] scan: scanning title 6
[10:56:24] scan: opening IFO for VTS 2
[10:56:24] pgc_id: 2, pgn: 1: pgc: 0x6751cc0
[10:56:24] scan: vts=2, ttn=2, cells=0->1, blocks=4->5697, 5694 blocks
[10:56:24] scan: duration is 00:00:20 (20033 ms)
[10:56:24] scan: checking audio 1
[10:56:24] scan: id=80bd, lang=English (AC3), 3cc=eng
[10:56:24] scan: title 6 has 2 chapters
[10:56:24] scan: chap 1 c=0->0, b=4->69 (66), 5000 ms
[10:56:24] scan: chap 2 c=1->1, b=70->5697 (5628), 15033 ms
[10:56:24] scan: aspect = 16
[10:56:24] scan: scanning title 7
[10:56:24] scan: opening IFO for VTS 2
[10:56:24] pgc_id: 3, pgn: 1: pgc: 0x673ec60
[10:56:24] scan: vts=2, ttn=3, cells=0->0, blocks=0->3, 4 blocks
[10:56:24] scan: title too short (4 blocks), ignoring
[10:56:24] scan: scanning title 8
[10:56:24] scan: opening IFO for VTS 2
[10:56:24] pgc_id: 4, pgn: 1: pgc: 0x673ee60
[10:56:24] scan: vts=2, ttn=4, cells=0->0, blocks=0->3, 4 blocks
[10:56:24] scan: title too short (4 blocks), ignoring
[10:56:24] scan: scanning title 9
[10:56:24] scan: opening IFO for VTS 2
[10:56:24] pgc_id: 5, pgn: 1: pgc: 0x673ef60
[10:56:24] scan: vts=2, ttn=5, cells=0->0, blocks=0->3, 4 blocks
[10:56:24] scan: title too short (4 blocks), ignoring
[10:56:24] scan: decoding previews for title 1
[10:56:24] scan: preview 1
[10:56:24] scan: AC3, rate=48000Hz, bitrate=448000
[10:56:24] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:24] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:24] scan: preview 2
[10:56:24] scan: preview 3
[10:56:24] scan: preview 4
[10:56:24] scan: preview 5
[10:56:24] scan: preview 6
[10:56:24] Title's mostly progressive NTSC, setting fps to 23.976
[10:56:24] scan: preview 7
[10:56:24] scan: preview 8
[10:56:24] scan: preview 9
[10:56:24] scan: preview 10
[10:56:25] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[10:56:25] scan: decoding previews for title 2
[10:56:25] scan: preview 1
[10:56:25] scan: AC3, rate=48000Hz, bitrate=448000
[10:56:25] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:25] scan: preview 2
[10:56:25] scan: preview 3
[10:56:25] scan: preview 4
[10:56:25] scan: preview 5
[10:56:25] scan: preview 6
[10:56:25] Title's mostly progressive NTSC, setting fps to 23.976
[10:56:25] scan: preview 7
[10:56:25] scan: preview 8
[10:56:25] scan: preview 9
[10:56:25] scan: preview 10
[10:56:25] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[10:56:25] scan: decoding previews for title 3
[10:56:25] scan: preview 1
[10:56:25] scan: AC3, rate=48000Hz, bitrate=448000
[10:56:25] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:25] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:25] scan: preview 2
[10:56:25] scan: preview 3
[10:56:25] scan: preview 4
[10:56:25] scan: preview 5
[10:56:25] scan: preview 6
[10:56:25] Title's mostly progressive NTSC, setting fps to 23.976
[10:56:25] scan: preview 7
[10:56:25] scan: preview 8
[10:56:25] scan: preview 9
[10:56:25] scan: preview 10
[10:56:25] scan: 720x480, 23.976 fps, autocrop = 0/0/0/0
[10:56:25] scan: decoding previews for title 6
[10:56:25] scan: preview 1
[10:56:25] scan: AC3, rate=48000Hz, bitrate=192000
[10:56:25] scan: preview 2
[10:56:26] 3.984333s: Progressive -> Interlaced
[10:56:26] scan: preview 3
[10:56:26] 5.302311s: Interlaced -> Progressive
[10:56:26] scan: preview 4
[10:56:26] scan: preview 5
[10:56:26] scan: preview 6
[10:56:26] scan: preview 7
[10:56:26] scan: preview 8
[10:56:26] scan: preview 9
[10:56:26] scan: preview 10
[10:56:26] scan: 720x480, 29.970 fps, autocrop = 0/0/0/0
[10:56:26] scan: title (0) job->width:720, job->height:400
[10:56:26] scan: title (1) job->width:720, job->height:400
[10:56:26] scan: title (2) job->width:720, job->height:400
[10:56:26] scan: title (3) job->width:720, job->height:400
[10:56:26] thread 182fa00 exited ("scan")
[10:56:26] thread 182fa00 joined ("scan")
[10:56:26] libhb: scan thread found 4 valid title(s)
[11:18:31] thread 1913400 started ("work")
[11:18:31] 1 job(s) to process
[11:18:31] starting job
[11:18:31] + device /Volumes/Jive Turkey/Video Temp/Television Shows/Sopranos s6d3/VIDEO_TS
[11:18:31] + title 1, chapter(s) 1 to 8
[11:18:31] + 720x480 -> 720x400, crop 0/0/0/0
[11:18:31] + grayscale off
[11:18:31] + filters
[11:18:31] + 23.976 fps, video bitrate 1000 kbps, pass 0
[11:18:31] + PixelRatio: 0, width:720, height: 400
[11:18:31] + encoder FFmpeg
[11:18:31] + audio 160 kbps, 48000 Hz
[11:18:31] + encoder faac
[11:18:31] + 80bd, English (AC3) (5.1 ch)
[11:18:31] + Requested mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[11:18:31] + Actual mixdown: Dolby Pro Logic II (HB_AMIXDOWN_DOLBYPLII)
[11:18:31] thread 1830400 started ("reader")
[11:18:31] + output: /Volumes/Jive Turkey/Video Temp/Television Shows/THE_SOPRANOS 6-1 classic all.m4v
[11:18:31] thread 18c9e00 started ("muxer")
[11:18:31] thread 190ac00 started ("MPEG-2 decoder (libmpeg2)")
[11:18:31] thread 1916a00 started ("Renderer")
[11:18:31] DVD: Beginning of Cell (0) at block 0
[mpeg4 @ 0x3aa7ec]removing common factors from framerate
[11:18:31] thread 18ca200 started ("MPEG-4 encoder (libavcodec)")
No accelerated IMDCT transform found
[11:18:31] thread 1889a00 started ("AC3 decoder")
[11:18:31] thread 1da3a00 started ("AAC encoder (libfaac)")
[11:18:31] sync: expecting 78910 video frames
[11:18:31] sync: first pts is 31263
[11:18:33] 5.802811s: Progressive -> Interlaced
[11:18:33] 6.253267s: Interlaced -> Progressive
[11:19:14] DVD: End of Cell (0) at block 48897
[11:19:14] DVD: Beginning of Cell (1) at block 48927
[11:19:14] DVD: Chapter Break Cell Found
[11:19:17] MPEG2: Chapter Break Cell Found, searching for GOP
[11:19:17] MPEG2: Group of pictures found, searching for I-Frame
[11:19:17] MPEG2: I-Frame Found
[11:19:17] MPEG2: Chapter Break Inserted
[11:19:19] WORK: Copying Chapter Break
[11:19:19] WORK: Copying Chapter Break
[11:22:16] DVD: End of Cell (1) at block 173928
[11:22:16] DVD: Beginning of Cell (2) at block 174343
[11:22:16] DVD: Chapter Break Cell Found
[11:22:19] MPEG2: Chapter Break Cell Found, searching for GOP
[11:22:19] MPEG2: Group of pictures found, searching for I-Frame
[11:22:19] MPEG2: I-Frame Found
[11:22:19] MPEG2: Chapter Break Inserted
[11:22:20] WORK: Copying Chapter Break
[11:22:21] WORK: Copying Chapter Break
[11:24:04] DVD: End of Cell (2) at block 275877
[11:24:04] DVD: Beginning of Cell (3) at block 276033
[11:24:04] DVD: Chapter Break Cell Found
[11:24:06] MPEG2: Chapter Break Cell Found, searching for GOP
[11:24:06] MPEG2: Group of pictures found, searching for I-Frame
[11:24:06] MPEG2: I-Frame Found
[11:24:06] MPEG2: Chapter Break Inserted
[11:24:07] WORK: Copying Chapter Break
[11:24:07] WORK: Copying Chapter Break
[11:28:00] DVD: End of Cell (3) at block 523446
[11:28:01] DVD: Beginning of Cell (4) at block 523593
[11:28:01] DVD: Chapter Break Cell Found
[11:28:03] MPEG2: Chapter Break Cell Found, searching for GOP
[11:28:03] MPEG2: Group of pictures found, searching for I-Frame
[11:28:03] MPEG2: I-Frame Found
[11:28:03] MPEG2: Chapter Break Inserted
[11:28:04] WORK: Copying Chapter Break
[11:28:04] WORK: Copying Chapter Break
[11:33:00] DVD: End of Cell (4) at block 823390
[11:33:00] DVD: Beginning of Cell (5) at block 823555
[11:33:00] DVD: Chapter Break Cell Found
[11:33:03] MPEG2: Chapter Break Cell Found, searching for GOP
[11:33:03] MPEG2: Group of pictures found, searching for I-Frame
[11:33:03] MPEG2: I-Frame Found
[11:33:03] MPEG2: Chapter Break Inserted
[11:33:04] WORK: Copying Chapter Break
[11:33:04] WORK: Copying Chapter Break
[11:35:02] DVD: End of Cell (5) at block 947597
[11:35:02] DVD: Beginning of Cell (6) at block 947880
[11:35:02] DVD: Chapter Break Cell Found
[11:35:06] MPEG2: Chapter Break Cell Found, searching for GOP
[11:35:06] MPEG2: Group of pictures found, searching for I-Frame
[11:35:06] MPEG2: I-Frame Found
[11:35:06] MPEG2: Chapter Break Inserted
[11:35:07] WORK: Copying Chapter Break
[11:35:07] WORK: Copying Chapter Break
[11:39:17] DVD: End of Cell (6) at block 1207258
[11:39:17] DVD: Beginning of Cell (7) at block 1207464
[11:39:17] DVD: Chapter Break Cell Found
[11:39:20] MPEG2: Chapter Break Cell Found, searching for GOP
[11:39:20] MPEG2: Group of pictures found, searching for I-Frame
[11:39:20] MPEG2: I-Frame Found
[11:39:20] MPEG2: Chapter Break Inserted
[11:39:20] 3155.499268s: Progressive -> Interlaced
[11:39:21] WORK: Copying Chapter Break
[11:39:21] WORK: Copying Chapter Break
[11:40:02] DVD: End of Cell (7) at block 1256877
[11:40:02] DVD: End of Cell (8) at block 3637000
[11:40:02] DVD: Beginning of Cell (9) at block 3637000
[11:40:02] DVD: Cell Found (4)
[11:40:02] reader: end of the title reached
[11:40:02] reader: done
[11:40:02] thread 1830400 exited ("reader")
[11:40:03] 3287.464600s: Interlaced -> Progressive
[11:40:05] sync: got 78910 frames
[11:40:05] thread 1889a00 exited ("AC3 decoder")
[11:40:05] thread 190ac00 exited ("MPEG-2 decoder (libmpeg2)")
[11:40:05] thread 190ac00 joined ("MPEG-2 decoder (libmpeg2)")
[11:40:05] thread 1da3a00 exited ("AAC encoder (libfaac)")
[11:40:05] thread 18ca200 exited ("MPEG-4 encoder (libavcodec)")
[11:40:05] thread 1916a00 exited ("Renderer")
[11:40:05] thread 1916a00 joined ("Renderer")
[11:40:05] fifo_close: trashing 0 buffer(s)
[11:40:05] thread 18ca200 joined ("MPEG-4 encoder (libavcodec)")
[11:40:05] encavcodec: closing libavcodec
[11:40:05] thread 1889a00 joined ("AC3 decoder")
[11:40:05] thread 1da3a00 joined ("AAC encoder (libfaac)")
[11:40:05] thread 1830400 joined ("reader")
[11:40:51] mux: file size, 478931261 bytes
[11:40:51] mux: track 0, 411702500 bytes, 1000.73 kbps
[11:40:51] mux: video bitrate error, +301928 bytes
[11:40:51] mux: track 1, 65793132 bytes, 159.92 kbps
[11:40:51] mux: overhead, 6.16 bytes per frame
[11:40:51] thread 18c9e00 exited ("muxer")
[11:40:51] thread 18c9e00 joined ("muxer")
[11:40:51] fifo_close: trashing 4 buffer(s)
[11:40:51] fifo_close: trashing 32 buffer(s)
[11:40:51] fifo_close: trashing 0 buffer(s)
[11:40:51] fifo_close: trashing 0 buffer(s)
[11:40:51] fifo_close: trashing 0 buffer(s)
[11:40:51] fifo_close: trashing 0 buffer(s)
[11:40:51] fifo_close: trashing 1 buffer(s)
[11:40:51] fifo_close: trashing 32 buffer(s)
[11:40:51] fifo_close: trashing 33 buffer(s)
[11:40:51] Freed 4 buffers of size 512
[11:40:51] Freed 5 buffers of size 1024
[11:40:51] Freed 2048 buffers of size 2048
[11:40:51] Freed 0 buffers of size 4096
[11:40:51] Freed 0 buffers of size 8192
[11:40:51] Freed 66 buffers of size 16384
[11:40:51] Freed 0 buffers of size 32768
[11:40:51] Freed 123 buffers of size 518400
[11:40:51] Allocated 69046016 bytes of buffers on this pass and Freed 69046016 bytes, 0 bytes leaked
[11:40:51] thread 1913400 exited ("work")
[11:40:51] thread 1913400 joined ("work")
[11:40:51] libhb: work result = 0
Top
sdm
Bright Spark User
Posts: 194
Joined: Mon Feb 19, 2007 4:53 pm

Re: Missing Frames in HB encodes

Post by sdm »

Update:

I've been watching progress discussed in a development thread "Same as Source".
Based on that, I compiled an SVN version of HB today, and tested it with Normal Preset + VFR.
The missing frame(s) are back!! The audio/video sync is good throughout (from what I can tell)
I'll watch the entire episode and report if I find issues.

Thanks to Devs

--sdm.
Top
Post Reply

Return to “Bugs”