File which is produced is a small part of what should be there, even though it knows the file is much longer

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
barts185
Posts: 2
Joined: Mon Feb 13, 2017 3:51 am

File which is produced is a small part of what should be there, even though it knows the file is much longer

Post by barts185 »

Please describe the problem in as much detail as possible:
Even though HandBrake identifies that the video is 2:23:03, it produces a file which is just over a minute long.

What are the steps to reproduce this problem:
Anytime I try to run the process for this file it happens. It has happened on other files as well.

What version of HandBrake you are running:
1.0.2 (2017012200)

What operating system and version and you running (e.g. OSX 10.11, Windows 7, Ubuntu 14):
Windows 10 latest update

If there was any exception or error displayed, please copy it and paste it here:
No error message. If finishes as though everything is okay, but the file it produces is very short.

Please include the scan or encode log:

Code: Select all

 HandBrake 1.0.2 (2017012200) - 64bit
OS: Microsoft Windows NT 10.0.14393.0 - 64bit
CPU: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz
Ram: 8166 MB, 
GPU Information:
  NVIDIA GeForce GTX 550 Ti - 21.21.13.7653
Screen: 1680x1050
Temp Dir: C:\Users\Michael\AppData\Local\Temp\
Install Dir: C:\Program Files\Handbrake
Data Dir: C:\Users\Michael\AppData\Roaming\HandBrake Team\HandBrake\1.0.2.0

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


# Starting Encode ...

[22:47:27] hb_init: starting libhb thread
[22:47:27] 1 job(s) to process
[22:47:27] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": 65536,
        "Gain": 0.0,
        "Mixdown": 4,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      1073807360,
      1073743872,
      1074003968,
      1073750016,
      1090519040,
      1074790400,
      1074266112,
      1107296256
    ],
    "FallbackEncoder": 2048
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      },
      {
        "Name": "Chapter 2"
      },
      {
        "Name": "Chapter 3"
      },
      {
        "Name": "Chapter 4"
      },
      {
        "Name": "Chapter 5"
      },
      {
        "Name": "Chapter 6"
      },
      {
        "Name": "Chapter 7"
      },
      {
        "Name": "Chapter 8"
      },
      {
        "Name": "Chapter 9"
      },
      {
        "Name": "Chapter 10"
      },
      {
        "Name": "Chapter 11"
      },
      {
        "Name": "Chapter 12"
      },
      {
        "Name": "Chapter 13"
      },
      {
        "Name": "Chapter 14"
      },
      {
        "Name": "Chapter 15"
      },
      {
        "Name": "Chapter 16"
      },
      {
        "Name": "Chapter 17"
      },
      {
        "Name": "Chapter 18"
      },
      {
        "Name": "Chapter 19"
      },
      {
        "Name": "Chapter 20"
      },
      {
        "Name": "Chapter 21"
      },
      {
        "Name": "Chapter 22"
      },
      {
        "Name": "Chapter 23"
      },
      {
        "Name": "Chapter 24"
      },
      {
        "Name": "Chapter 25"
      },
      {
        "Name": "Chapter 26"
      }
    ],
    "ChapterMarkers": true,
    "File": "C:\\Users\\Michael\\Videos\\Magic\\SIGNATURE_EFFECTS_DVD-1-1-26.m4v",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": 131072
  },
  "Filters": {
    "FilterList": [
      {
        "ID": 4,
        "Settings": {
          "mode": "7"
        }
      },
      {
        "ID": 3,
        "Settings": {
          "block-height": "16",
          "block-thresh": "40",
          "block-width": "16",
          "filter-mode": "2",
          "mode": "3",
          "motion-thresh": "1",
          "spatial-metric": "2",
          "spatial-thresh": "1"
        }
      },
      {
        "ID": 11,
        "Settings": {
          "crop-bottom": "2",
          "crop-left": "10",
          "crop-right": "6",
          "crop-top": "0",
          "height": "478",
          "width": "704"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 8,
    "Den": 9
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 26
    },
    "Title": 1,
    "Path": "E:\\"
  },
  "Subtitle": {
    "Search": {
      "Burn": true,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": 65536,
    "Level": "4.0",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 22.0,
    "OpenCL": false,
    "HWDecode": false,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[22:47:27] CPU: Intel(R) Core(TM) i7-2600 CPU @ 3.40GHz
[22:47:27]  - Intel microarchitecture Sandy Bridge
[22:47:27]  - logical processor count: 8
[22:47:27] Intel Quick Sync Video support: no
[22:47:27] hb_scan: path=E:\, title_index=1
src/libbluray/disc/disc.c:352: error opening file BDMV\index.bdmv
src/libbluray/disc/disc.c:352: error opening file BDMV\BACKUP\index.bdmv
[22:47:27] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdread: Can't open E:\ for reading
libdvdread: Device E:\ inaccessible, CSS authentication not available.
libdvdnav: Unable to open device file E:\.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Can't open E:\ for reading
libdvdread: Device E:\ inaccessible, CSS authentication not available.
[22:47:27] scan: DVD has 7 title(s)
[22:47:27] scan: scanning title 1
[22:47:27] scan: opening IFO for VTS 1
[22:47:27] scan: duration is 02:23:59 (8639033 ms)
[22:47:27] pgc_id: 1, pgn: 1: pgc: 00000250EB77C180
[22:47:27] scan: vts=1, ttn=1, cells=0->25, blocks=0->2133858, 2133859 blocks
[22:47:27] scan: checking audio 1
[22:47:27] scan: id=0xa0bd, lang=Unknown (LPCM), 3cc=und ext=0
[22:47:27] scan: title 1 has 26 chapters
[22:47:27] scan: chap 1 c=0->0, b=0->19150 (19151), 68867 ms
[22:47:27] scan: chap 2 c=1->1, b=19151->110204 (91054), 375467 ms
[22:47:27] scan: chap 3 c=2->2, b=110205->195130 (84926), 382300 ms
[22:47:27] scan: chap 4 c=3->3, b=195131->267218 (72088), 287900 ms
[22:47:27] scan: chap 5 c=4->4, b=267219->287285 (20067), 85500 ms
[22:47:27] scan: chap 6 c=5->5, b=287286->345238 (57953), 260500 ms
[22:47:27] scan: chap 7 c=6->6, b=345239->444333 (99095), 438800 ms
[22:47:27] scan: chap 8 c=7->7, b=444334->719898 (275565), 1077767 ms
[22:47:27] scan: chap 9 c=8->8, b=719899->860398 (140500), 545166 ms
[22:47:27] scan: chap 10 c=9->9, b=860399->1014049 (153651), 611867 ms
[22:47:27] scan: chap 11 c=10->10, b=1014050->1161330 (147281), 621567 ms
[22:47:27] scan: chap 12 c=11->11, b=1161331->1373237 (211907), 903367 ms
[22:47:27] scan: chap 13 c=12->12, b=1373238->1396906 (23669), 85767 ms
[22:47:27] scan: chap 14 c=13->13, b=1396907->1535347 (138441), 499066 ms
[22:47:27] scan: chap 15 c=14->14, b=1535348->1618216 (82869), 304433 ms
[22:47:27] scan: chap 16 c=15->15, b=1618217->1654607 (36391), 144500 ms
[22:47:27] scan: chap 17 c=16->16, b=1654608->1687417 (32810), 140500 ms
[22:47:27] scan: chap 18 c=17->17, b=1687418->1709588 (22171), 93000 ms
[22:47:27] scan: chap 19 c=18->18, b=1709589->1736571 (26983), 113500 ms
[22:47:27] scan: chap 20 c=19->19, b=1736572->1760351 (23780), 105500 ms
[22:47:27] scan: chap 21 c=20->20, b=1760352->1831953 (71602), 285500 ms
[22:47:27] scan: chap 22 c=21->21, b=1831954->1906214 (74261), 290500 ms
[22:47:27] scan: chap 23 c=22->22, b=1906215->1979006 (72792), 293500 ms
[22:47:27] scan: chap 24 c=23->23, b=1979007->2088574 (109568), 429000 ms
[22:47:27] scan: chap 25 c=24->24, b=2088575->2127950 (39376), 167000 ms
[22:47:27] scan: chap 26 c=25->25, b=2127951->2133858 (5908), 28200 ms
[22:47:27] scan: aspect = 4:3
[22:47:27] scan: decoding previews for title 1
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
[22:47:27] scan: title angle(s) 1
[22:47:28] scan: audio 0xa0bd: LPCM, rate=48000Hz, bitrate=1536000 Unknown (LPCM) (2.0 ch)
[22:47:28] scan: 10 previews, 720x480, 29.970 fps, autocrop = 0/2/10/6, aspect 4:3, PAR 8:9
[22:47:28] libhb: scan thread found 1 valid title(s)
[22:47:28] Skipping subtitle scan.  No suitable subtitle tracks.
[22:47:28] starting job
[22:47:28] decomb filter thread started for segment 0
[22:47:28] decomb filter thread started for segment 3
[22:47:28] decomb filter thread started for segment 1
[22:47:28] decomb filter thread started for segment 2
[22:47:28] decomb filter thread started for segment 4
[22:47:28] decomb filter thread started for segment 5
[22:47:28] decomb filter thread started for segment 6
[22:47:28] decomb filter thread started for segment 7
[22:47:28] decomb check thread started for segment 1
[22:47:28] decomb check thread started for segment 0
[22:47:28] decomb check thread started for segment 3
[22:47:28] decomb check thread started for segment 5
[22:47:28] decomb check thread started for segment 4
[22:47:28] decomb check thread started for segment 6
[22:47:28] mask filter thread started for segment 0
[22:47:28] decomb check thread started for segment 7
[22:47:28] mask filter thread started for segment 3
[22:47:28] mask filter thread started for segment 1
[22:47:28] mask filter thread started for segment 6
[22:47:28] mask filter thread started for segment 7
[22:47:28] mask filter thread started for segment 4
[22:47:28] mask erode thread started for segment 1
[22:47:28] mask erode thread started for segment 0
[22:47:28] mask filter thread started for segment 2
[22:47:28] mask erode thread started for segment 6
[22:47:28] mask erode thread started for segment 3
[22:47:28] mask dilate thread started for segment 0
[22:47:28] mask dilate thread started for segment 1
[22:47:28] mask erode thread started for segment 4
[22:47:28] mask dilate thread started for segment 3
[22:47:28] mask dilate thread started for segment 4
[22:47:28] mask erode thread started for segment 7
[22:47:28] decomb check thread started for segment 2
[22:47:28] mask erode thread started for segment 2
[22:47:28] mask dilate thread started for segment 2
[22:47:28] mask filter thread started for segment 5
[22:47:28] mask dilate thread started for segment 5
[22:47:28] mask erode thread started for segment 5
[22:47:28] yadif thread started for segment 2
[22:47:28] mask dilate thread started for segment 7
[22:47:28] yadif thread started for segment 4
[22:47:28] yadif thread started for segment 5
[22:47:28] yadif thread started for segment 6
[22:47:28] yadif thread started for segment 3
[22:47:28] yadif thread started for segment 0
[22:47:28] mask dilate thread started for segment 6
[22:47:28] work: track 1, dithering not supported by codec
[22:47:28] yadif thread started for segment 1
[22:47:28] yadif thread started for segment 7
[22:47:28] job configuration:
[22:47:28]  * source
[22:47:28]    + E:\
[22:47:28]    + title 1, chapter(s) 1 to 26
[22:47:28]  * destination
[22:47:28]    + C:\Users\Michael\Videos\Magic\SIGNATURE_EFFECTS_DVD-1-1-26.m4v
[22:47:28]    + container: MPEG-4 (libavformat)
[22:47:28]      + chapter markers
[22:47:28]  * video track
[22:47:28]    + decoder: mpeg2video
[22:47:28]      + bitrate 7000 kbps
[22:47:28]    + filters
[22:47:28]      + Comb Detect (mode=3:spatial-metric=2:motion-thresh=1:spatial-thresh=1:filter-mode=2:block-thresh=40:block-width=16:block-height=16)
[22:47:28]      + Decomb (mode=39)
[22:47:28]      + Framerate Shaper (mode=2:rate=27000000/900000)
[22:47:28]        + frame rate: 29.970 fps -> peak rate limited to 30.000 fps
[22:47:28]      + Crop and Scale (width=704:height=478:crop-top=0:crop-bottom=2:crop-left=10:crop-right=6)
[22:47:28]        + source: 720 * 480, crop (0/2/10/6): 704 * 478, scale: 704 * 478
[22:47:28]    + Output geometry
[22:47:28]      + storage dimensions: 704 x 478
[22:47:28]      + pixel aspect ratio: 8 : 9
[22:47:28]      + display dimensions: 625 x 478
[22:47:28]    + encoder: H.264 (libx264)
[22:47:28]      + preset:  fast
[22:47:28]      + profile: main
[22:47:28]      + level:   4.0
[22:47:28]      + quality: 22.00 (RF)
[22:47:28]  * audio track 1
[22:47:28]    + decoder: Unknown (LPCM) (2.0 ch) (track 1, id 0xa0bd)
[22:47:28]      + bitrate: 1536 kbps, samplerate: 48000 Hz
[22:47:28]    + mixdown: Stereo
[22:47:28]    + encoder: AAC (libavcodec)
[22:47:28]      + bitrate: 160 kbps, samplerate: 48000 Hz
libdvdnav: Using dvdnav version 5.0.1
libdvdread: Encrypted DVD support unavailable.
libdvdread: Can't open E:\ for reading
libdvdread: Device E:\ inaccessible, CSS authentication not available.
libdvdnav: Unable to open device file E:\.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
libdvdread: Encrypted DVD support unavailable.
libdvdread: Can't open E:\ for reading
libdvdread: Device E:\ inaccessible, CSS authentication not available.
libdvdnav: DVD disk reports itself with Region mask 0x00400000. Regions: 1 2 3 4 5 6 8
[22:47:28] sync: expecting 258912 video frames
[22:47:28] encx264: min-keyint: 30, keyint: 300
[22:47:28] encx264: encoding at constant RF 22.000000
[22:47:28] encx264: unparsed options: weightp=1:ref=2:level=4.0:8x8dct=0:vbv-bufsize=25000:subme=6:vbv-maxrate=20000:rc-lookahead=30
x264 [info]: using SAR=8/9
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
x264 [info]: profile Main, level 4.0
[22:47:28] sync: first pts video is 0
[22:47:28] sync: "Chapter 1" (1) at frame 1 time 0
[22:47:28] sync: first pts audio 0xa0bd is 0
[22:47:42] reader: done. 1 scr changes
[22:47:43] work: average encoding speed for job is 138.857147 fps
[22:47:43] comb detect: heavy 1512 | light 174 | uncombed 379 | total 2065
[22:47:43] decomb: deinterlaced 1512 | blended 174 | unfiltered 379 | total 2065
[22:47:43] vfr: 2065 frames output, 0 dropped and 0 duped for CFR/PFR
[22:47:43] vfr: lost time: 0 (0 frames)
[22:47:43] vfr: gained time: 0 (0 frames) (0 not accounted for)
[22:47:43] mpeg2video-decoder done: 2065 frames, 0 decoder errors
[22:47:43] sync: got 2065 frames, 258912 expected
[22:47:43] sync: framerate min 29.970 fps, max 29.970 fps, avg 29.970 fps
x264 [info]: frame I:18    Avg QP:17.05  size: 26166
x264 [info]: frame P:614   Avg QP:21.86  size: 12383
x264 [info]: frame B:1433  Avg QP:24.27  size:  5276
x264 [info]: consecutive B-frames:  4.1%  8.5%  4.6% 82.7%
x264 [info]: mb I  I16..4: 59.7%  0.0% 40.3%
x264 [info]: mb P  I16..4:  9.3%  0.0%  9.5%  P16..4: 35.1% 13.3%  7.0%  0.0%  0.0%    skip:25.8%
x264 [info]: mb B  I16..4:  2.9%  0.0%  0.5%  B16..8: 23.1%  9.2%  1.7%  direct:17.9%  skip:44.6%  L0:36.5% L1:48.1% BI:15.4%
x264 [info]: coded y,uvDC,uvAC intra: 42.4% 55.6% 26.4% inter: 17.8% 35.5% 5.1%
x264 [info]: i16 v,h,dc,p: 50% 27% 16%  7%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 18% 20% 20%  6%  7%  6%  9%  5%  8%
x264 [info]: i8c dc,h,v,p: 60% 23% 11%  5%
x264 [info]: Weighted P-Frames: Y:18.7% UV:17.6%
x264 [info]: ref P L0: 69.4% 30.6%
x264 [info]: ref B L0: 86.4% 13.6%
x264 [info]: ref B L1: 94.5%  5.5%
x264 [info]: kb/s:1815.34
[22:47:44] mux: track 0, 2065 frames, 15634483 bytes, 1814.39 kbps, fifo 2048
[22:47:44] mux: track 1, 3218 frames, 1378089 bytes, 159.93 kbps, fifo 4096
[22:47:44] libhb: work result = 0

# Encode Completed ...

 
rollin_eng
Veteran User
Posts: 4854
Joined: Wed May 04, 2011 11:06 pm

Re: File which is produced is a small part of what should be there, even though it knows the file is much longer

Post by rollin_eng »

Rip your disc with makemkv first.
barts185
Posts: 2
Joined: Mon Feb 13, 2017 3:51 am

Re: File which is produced is a small part of what should be there, even though it knows the file is much longer

Post by barts185 »

Thanks - I'm trying that now.

Maybe it's because my DVD player is too slow (although I've ripped DVDs in the past and it hasn't taken anywhere near this long), but it's taking 2-3 hours to rip a 2 hour and 23 minute video.
Woodstock
Veteran User
Posts: 4619
Joined: Tue Aug 27, 2013 6:39 am

Re: File which is produced is a small part of what should be there, even though it knows the file is much longer

Post by Woodstock »

More likely the disk is damaged or dirty, and it is fighting to get valid data off of it.
Post Reply