[svn6239] inconsistant frame count

Ayoross · Post by **Ayoross** » Sat Jul 26, 2014 6:42 pm

Look at that :

[12:07:35] bd: duration is 00:58:19 (3499040 ms)
(...)
[12:07:36] scan: 10 previews, 1920x1080, 25.000 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
(...)
[12:07:36] sync: expecting 2473568 video frames
(...)
[18:40:59] sync: got 87475 frames, 2473568 expected
(...)
[18:41:00] stream: 87476 good frames, 0 errors (0%)

There seems to be a bug with "sync" : 3499.040 s times 25 fps equals exactly 87476 frames, not 2473568.

Full log:

Code: Select all

HandBrake svn6239 (Nightly Build) - 64bit Version
OS: Microsoft Windows NT 6.1.7601 Service Pack 1
CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
Ram: 16311 MB, 
GPU Information:
  NVIDIA GeForce GTX 580 - 9.18.13.3788
Screen: 1920x1200
Temp Dir: C:\Users\FG\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\FG\AppData\Roaming\HandBrake\HandBrake\0.0.0.6239

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

CLI Query:  -i "H:\\" -t 8 --angle 1 -c 1-7 -o "D:\DVD\Real humans S1E04.mkv"  -f mkv  --crop 0:0:0:0 --strict-anamorphic  --modulus 16 -e x265 -q 21.5 --vfr -a 4,2 -E copy:dtshd,copy:dtshd -6 none,none -R Auto,Auto -B 0,0 -D 0,0 --gain 0,0 --audio-fallback ac3 --subtitle 2,1 --markers="C:\Users\FG\AppData\Local\Temp\Real humans S1E04-8-chapters.csv" --encoder-preset=slow  --encoder-profile=main  --verbose=1

[12:07:35] hb_init: starting libhb thread
HandBrake svn6239 (2014072201) - MinGW x86_64 - http://handbrake.fr
12 CPUs detected
Opening H:\...
[12:07:35] CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz
[12:07:35]  - Intel microarchitecture Sandy Bridge
[12:07:35]  - logical processor count: 12
[12:07:35] OpenCL device #1: NVIDIA Corporation GeForce GTX 580
[12:07:35]  - OpenCL version: 1.1 CUDA
[12:07:35]  - driver version: 337.88
[12:07:35]  - device type:    GPU
[12:07:35]  - supported:      no
[12:07:35] Intel Quick Sync Video support: no
[12:07:35] hb_scan: path=H:\, title_index=8
[12:07:35] scan: BD has 12 title(s)
[12:07:35] bd: scanning title 8
[12:07:35] bd: playlist 01003.MPLS
[12:07:35] bd: duration is 00:58:19 (3499040 ms)
[12:07:35] bd: video id=0x1011, stream type=H.264, format 1080i
[12:07:35] bd: aspect = 1.77778
[12:07:35] bd: audio id=0x711100, lang=Francais (DTS), 3cc=fra
[12:07:35] bd: audio id=0x1100, lang=Francais (DTS-HD MA), 3cc=fra
[12:07:35] bd: audio id=0x711101, lang=Svenska (DTS), 3cc=swe
[12:07:35] bd: audio id=0x1101, lang=Svenska (DTS-HD MA), 3cc=swe
[12:07:35] bd: subtitle id=0x1200, lang=Francais, 3cc=fra
[12:07:35] bd: subtitle id=0x1201, lang=Francais, 3cc=fra
[12:07:35] bd: chap 1 packet=768, 210520 ms
[12:07:35] bd: chap 2 packet=699184896, 525400 ms
[12:07:35] bd: chap 3 packet=2521992960, 708480 ms
[12:07:35] bd: chap 4 packet=4938145344, 556320 ms
[12:07:35] bd: chap 5 packet=6869523072, 745560 ms
[12:07:35] bd: chap 6 packet=9439214784, 753080 ms
[12:07:35] bd: chap 7 packet=11947049856, 95443397 ms
[12:07:35] bd: title 8 has 7 chapters
[12:07:35] scan: decoding previews for title 8
[12:07:35] scan: title angle(s) 1
[12:07:35] scan: audio 0x711100: dca, rate=48000Hz, bitrate=1536000 Francais (DTS) (2.0 ch)
[12:07:35] scan: audio 0x1100: dca, rate=48000Hz, bitrate=1536000 Francais (DTS-HD MA) (2.0 ch)
[12:07:35] scan: audio 0x711101: dca, rate=48000Hz, bitrate=1536000 Svenska (DTS) (5.1 ch)
[12:07:35] scan: audio 0x1101: dca, rate=48000Hz, bitrate=1536000 Svenska (DTS-HD MA) (5.1 ch)
Scanning title 1 of 1, preview 2, 20.00 %
Scanning title 1 of 1, preview 4, 40.00 %
Scanning title 1 of 1, preview 5, 50.00 %
Scanning title 1 of 1, preview 7, 70.00 %
Scanning title 1 of 1, preview 9, 90.00 %
Scanning title 1 of 1, preview 10, 100.00 %[12:07:36] scan: 10 previews, 1920x1080, 25.000 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[12:07:36] stream: 3 good frames, 0 errors (0%)
[12:07:36] libhb: scan thread found 1 valid title(s)
+ title 8:
  + playlist: 01003.MPLS
  + duration: 00:58:19
  + size: 1920x1080, pixel aspect: 1/1, display aspect: 1.78, 25.000 fps
  + autocrop: 0/0/0/0
  + support opencl: no
  + support hwd: no
  + chapters:
    + 1: cells 0->0, 0 blocks, duration 00:03:31
    + 2: cells 0->0, 0 blocks, duration 00:08:45
    + 3: cells 0->0, 0 blocks, duration 00:11:48
    + 4: cells 0->0, 0 blocks, duration 00:09:16
    + 5: cells 0->0, 0 blocks, duration 00:12:26
    + 6: cells 0->0, 0 blocks, duration 00:12:33
    + 7: cells 0->0, 0 blocks, duration 26:30:43
  + audio tracks:
    + 1, Francais (DTS) (2.0 ch) (iso639-2: fra), 48000Hz, 1536000bps
    + 2, Francais (DTS-HD MA) (2.0 ch) (iso639-2: fra)
    + 3, Svenska (DTS) (5.1 ch) (iso639-2: swe), 48000Hz, 1536000bps
    + 4, Svenska (DTS-HD MA) (5.1 ch) (iso639-2: swe)
  + subtitle tracks:
    + 1, Francais (iso639-2: fra) (Bitmap)(PGS)
    + 2, Francais (iso639-2: fra) (Bitmap)(PGS)
Reading chapter markers from file C:\Users\FG\AppData\Local\Temp\Real humans S1E04-8-chapters.csv
[12:07:36] 1 job(s) to process
[12:07:36] starting job
[12:07:36] sync: expecting 2473568 video frames
[12:07:36] job configuration:
[12:07:36]  * source
[12:07:36]    + H:\
[12:07:36]    + title 8, chapter(s) 1 to 7
[12:07:36]  * destination
[12:07:36]    + D:\DVD\Real humans S1E04.mkv
[12:07:36]    + container: Matroska (libavformat)
[12:07:36]      + chapter markers
[12:07:36]  * video track
[12:07:36]    + decoder: h264
[12:07:36]      + bitrate 200 kbps
[12:07:36]    + filters
[12:07:36]      + Framerate Shaper (0:27000000:1080000)
[12:07:36]        + frame rate: same as source (around 25.000 fps)
[12:07:36]      + Crop and Scale (1920:1080:0:0:0:0)
[12:07:36]        + source: 1920 * 1080, crop (0/0/0/0): 1920 * 1080, scale: 1920 * 1080
[12:07:36]    + strict anamorphic
[12:07:36]      + storage dimensions: 1920 * 1080, mod 16
[12:07:36]      + pixel aspect ratio: 1 / 1
[12:07:36]      + display dimensions: 1920 * 1080
[12:07:36]    + encoder: H.265 (libx265)
[12:07:36]      + preset:  slow
[12:07:36]      + profile: main
[12:07:36]      + quality: 21.50 (RF)
[12:07:36]  * subtitle track 1, Francais (track 1, id 0x1201) Picture [PGS] -> Passthrough
[12:07:36]  * subtitle track 2, Francais (track 0, id 0x1200) Picture [PGS] -> Passthrough
[12:07:36]  * audio track 1
[12:07:36]    + decoder: Svenska (DTS-HD MA) (5.1 ch) (track 4, id 0x1101)
[12:07:36]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[12:07:36]    + DTS-HD Passthru
[12:07:36]  * audio track 2
[12:07:36]    + decoder: Francais (DTS-HD MA) (2.0 ch) (track 2, id 0x1100)
[12:07:36]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[12:07:36]    + DTS-HD Passthru
[12:07:36] reader: first SCR 45000 id 0x1011 DTS 45000
x265 [info]: HEVC encoder version 1.0
x265 [info]: build info [Windows][GCC 4.8.0][64 bit] 8bpp
x265 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x265 [info]: WPP streams / pool / frames         : 17 / 12 / 3
x265 [info]: Main profile, Level-4 (Main tier)
x265 [info]: CU size                             : 64
x265 [info]: Max RQT depth inter / intra         : 1 / 1
x265 [info]: ME / range / subpel / merge         : star / 57 / 3 / 3
x265 [info]: Keyframe min / max / scenecut       : 25 / 250 / 40
x265 [info]: Lookahead / bframes / badapt        : 25 / 4 / 2
x265 [info]: b-pyramid / weightp / weightb / refs: 1 / 1 / 0 / 3
x265 [info]: Rate Control / AQ-Strength / CUTree : CRF-21.5 / 1.0 / 1
x265 [info]: tools: rect amp rd=4 lft sao-lcu sign-hide 
[12:07:36] h264: "Chapter 1" (1) at frame 0 time 3600
[12:07:36] sync: first pts is 3600
[12:36:41] h264: "Chapter 2" (2) at frame 5260 time 18939600
[13:32:05] h264: "Chapter 3" (3) at frame 18398 time 66236400
[14:50:21] h264: "Chapter 4" (4) at frame 36110 time 129999600
[15:59:56] h264: "Chapter 5" (5) at frame 50018 time 180068400
[17:20:39] h264: "Chapter 6" (6) at frame 68653 time 247154400
[18:40:54] reader: done. 2 scr changes
[18:40:56] h264: "Chapter 7" (7) at frame 87472 time 314902800
[18:40:59] work: average encoding speed for job is 3.706519 fps
[18:40:59] sync: got 87475 frames, 2473568 expected
[18:41:00] render: lost time: 0 (0 frames)
[18:41:00] render: gained time: 0 (0 frames) (0 not accounted for)
[18:41:00] h264-decoder done: 87475 frames, 0 decoder errors, 0 drops
x265 [info]: frame I: 747    Avg QP:17.97  kb/s: 17129.25
x265 [info]: frame P: 21644  Avg QP:21.13  kb/s: 4366.18 
x265 [info]: frame B: 65084  Avg QP:23.15  kb/s: 1110.05 
x265 [info]: global : 87475  Avg QP:22.61  kb/s: 2052.52 
x265 [info]: Weighted P-Frames: Y:1.2% UV:0.8%
x265 [info]: consecutive B-frames: 7.3% 5.4% 10.2% 43.5% 33.6% 
[18:41:00] dca-decoder done: 0 frames, 0 decoder errors, 0 drops
[18:41:00] dca-decoder done: 0 frames, 0 decoder errors, 0 drops
[18:41:00] mux: track 0, 87475 frames, 898069560 bytes, 2053.29 kbps, fifo 512
[18:41:00] mux: track 1, 328033 frames, 802601816 bytes, 1835.02 kbps, fifo 2048
[18:41:00] mux: track 2, 328033 frames, 929368600 bytes, 2124.85 kbps, fifo 2048
[18:41:00] mux: track 3, 1466 frames, 13912950 bytes, 31.81 kbps, fifo 32
[18:41:00] mux: track 4, 36 frames, 715041 bytes, 1.63 kbps, fifo 32
[18:41:00] stream: 87476 good frames, 0 errors (0%)
[18:41:00] libhb: work result = 0
Encode done!
HandBrake has exited.

Post by **JohnAStebbins** » Wed Jul 30, 2014 4:09 pm

Code: Select all

    + 7: cells 0->0, 0 blocks, duration 26:30:43

The duration of the last chapter in your BD is broken. HandBrake calculates the expected frames by summing the chapter durations since it is possible to only encode a chapter range.

Post by **JohnAStebbins** » Wed Jul 30, 2014 5:00 pm

FYI, this patch that I just put up for review should work around the problem
https://reviews.handbrake.fr/r/749/

Ayoross · Post by **Ayoross** » Thu Jul 31, 2014 3:23 pm

Ok, thanks. I'll test it as soon as it is applied on a nightly build.

HandBrake

[svn6239] inconsistant frame count

[svn6239] inconsistant frame count

Re: [svn6239] inconsistant frame count

Re: [svn6239] inconsistant frame count

Re: [svn6239] inconsistant frame count