Encoding taking extremely long times or not completing - negative numbers showing when converting MXF to MP4

HandBrake for Windows support
Forum rules
An Activity Log is required for support requests. Please read How-to get an activity log? for details on how and why this should be provided.
Post Reply
ssorj
New User
Posts: 1
Joined: Tue May 07, 2019 4:53 pm

Encoding taking extremely long times or not completing - negative numbers showing when converting MXF to MP4

Post by ssorj » Tue May 07, 2019 5:14 pm

Description of problem or question:
Using 1.2.2, I was experiencing an issue with encodings going into negative numbers for time remaining and taking an either never finishing the encode or taking an extremely long time to complete compared to the video size/length. I read to try the nightly build (HandBrake Nightly 20190425145519-f9bff38-master (2019042701)) and I am still experiencing the same issues. In the case listed here, I am attempting to transcode an MXF file coming from a Sony XDcam to mp4. The video is 53 minutes in length at 30fps, but took three and a half hours to transcode at ~30fps. When it got to the expected end of the transcoding, negative numbers were displayed in the time remaining and the fps number would flux between 2 and 30 for a few hours before it completed. In some cases, it doesn't complete overnight, so I abandon the transcode and try again.

Thank you for this program, I love the flexibility -- I'm just not understanding what I'm doing wrong here or maybe others are experiencing the same issue. I read that others were resolved with the nightly, but that didn't seem to work for me.

Steps to reproduce the problem (If Applicable):
Transcode any MXF file from a Sony XDcam to mp4



HandBrake version (e.g., 1.0.0):
HandBrake Nightly 20190425145519-f9bff38-master (2019042701)



Operating system and version (e.g., Ubuntu 16.04 LTS, macOS 10.13 High Sierra, Windows 10 Creators Update):
Microsoft Windows [Version 10.0.17134.706]


HandBrake Activity Log ***required*** (see How-to get an activity log)

Code: Select all

HandBrake Nightly 20190425145519-f9bff38-master (2019042701)
OS: Microsoft Windows NT 10.0.17134.0
CPU: Intel(R) Xeon(R) CPU E3-1225 v5 @ 3.30GHz
Ram: 32688 MB, 
GPU Information:
  NVIDIA Quadro M620 - 23.21.13.8826
Screen: 1680x1050
Temp Dir: C:\Users\jross\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake Nightly
Data Dir: C:\Users\jross\AppData\Roaming\HandBrake\Nightly

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


# Starting Encode ...

[04:28:09] hb_init: starting libhb thread
[04:28:09] 1 job(s) to process
[04:28:09] json job:
{
  "Audio": {
    "AudioList": [
      {
        "Bitrate": 160,
        "DRC": 0.0,
        "Encoder": "av_aac",
        "Gain": 0.0,
        "Mixdown": 1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Track": 0,
        "DitherMethod": 0
      }
    ],
    "CopyMask": [
      "copy:aac",
      "copy:ac3",
      "copy:dtshd",
      "copy:dts",
      "copy:eac3",
      "copy:flac",
      "copy:mp3",
      "copy:truehd"
    ],
    "FallbackEncoder": "ac3"
  },
  "Destination": {
    "ChapterList": [
      {
        "Name": "Chapter 1"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": true,
    "File": "C:\\Users\\jross\\Videos\\Steve Dawson - Dawson Forensic Group\\Clip0072-3.mp4",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "mp4"
  },
  "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": 12,
        "Settings": {
          "crop-bottom": "0",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "0",
          "height": "1080",
          "width": "1920"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/900000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "Metadata": {},
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 1
    },
    "Title": 3,
    "Path": "C:\\Users\\jross\\Videos\\Steve Dawson - Dawson Forensic Group\\Clip0072.MXF"
  },
  "Subtitle": {
    "Search": {
      "Burn": true,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": "x264",
    "Level": "4.0",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "fast",
    "Profile": "main",
    "Quality": 22.0,
    "QSV": {
      "Decode": false,
      "AsyncDepth": 0
    }
  }
}
[04:28:09] CPU: Intel(R) Xeon(R) CPU E3-1225 v5 @ 3.30GHz
[04:28:09]  - Intel microarchitecture Skylake
[04:28:09]  - logical processor count: 4
[04:28:09] Intel Quick Sync Video support: no
[04:28:09] hb_scan: path=C:\Users\jross\Videos\Steve Dawson - Dawson Forensic Group\Clip0072.MXF, title_index=3
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image C:\Users\jross\Videos\Steve Dawson - Dawson Forensic Group\Clip0072.MXF
src/libbluray/disc/disc.c:424: error opening file BDMV\index.bdmv
src/libbluray/disc/disc.c:424: error opening file BDMV\BACKUP\index.bdmv
[04:28:09] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 6.0.0
libdvdread: Encrypted DVD support unavailable.
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.IFO failed
libdvdread:DVDOpenFileUDF:UDFFindFile /VIDEO_TS/VIDEO_TS.BUP failed
libdvdread: Can't open file VIDEO_TS.IFO.
libdvdnav: vm: failed to read VIDEO_TS.IFO
[04:28:09] dvd: not a dvd - trying as a stream/file instead
Input #0, mxf, from 'C:\Users\jross\Videos\Steve Dawson - Dawson Forensic Group\Clip0072.MXF':
  Metadata:
    uid             : 19adc48a-a24c-1230-b71e-000000000000
    generation_uid  : 19adc494-a24c-1230-96a4-000000000000
    company_name    : Sony
    product_name    : Mem
    product_version : 2.00
    product_uid     : ffffffff-ffff-ffff-ffff-ffffffffffff
    modification_date: 2019-04-11T19:06:53.000000Z
    material_package_umid: 0x060A2B340101010501010D431300000050EFE3CB848505CE449160FFFE77F61E
    timecode        : 17:53:48;11
  Duration: 00:53:11.19, start: 0.000000, bitrate: 51371 kb/s
    Stream #0:0: Video: h264 (High 4:2:2), yuv422p10le(tv, bt709/bt709/iec61966-2-4, top first), 1920x1080 [SAR 1:1 DAR 16:9], 14.99 fps, 29.97 tbr, 29.97 tbn, 59.94 tbc
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
    Stream #0:1: Audio: pcm_s24le, 48000 Hz, 1 channels, s32 (24 bit), 1152 kb/s
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
    Stream #0:2: Audio: pcm_s24le, 48000 Hz, 1 channels, s32 (24 bit), 1152 kb/s
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
    Stream #0:3: Audio: pcm_s24le, 48000 Hz, 1 channels, s32 (24 bit), 1152 kb/s
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
    Stream #0:4: Audio: pcm_s24le, 48000 Hz, 1 channels, s32 (24 bit), 1152 kb/s
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
    Stream #0:5: Data: none
    Metadata:
      file_package_umid: 0x060A2B340101010501010D431300000051EFE3CB848505CE449160FFFE77F61E
      data_type       : vbi_vanc_smpte_436M
[04:28:09] scan: decoding previews for title 3
[04:28:10] scan: audio 0x1: pcm_s24le, rate=48000Hz, bitrate=1152000 Unknown (pcm_s24le) (1.0 ch) (1152 kbps)
[04:28:10] scan: audio 0x2: pcm_s24le, rate=48000Hz, bitrate=1152000 Unknown (pcm_s24le) (1.0 ch) (1152 kbps)
[04:28:10] scan: audio 0x3: pcm_s24le, rate=48000Hz, bitrate=1152000 Unknown (pcm_s24le) (1.0 ch) (1152 kbps)
[04:28:10] scan: audio 0x4: pcm_s24le, rate=48000Hz, bitrate=1152000 Unknown (pcm_s24le) (1.0 ch) (1152 kbps)
[04:28:13] scan: 10 previews, 1920x1080, 14.985 fps, autocrop = 0/0/0/0, aspect 16:9, PAR 1:1
[04:28:13] libhb: scan thread found 1 valid title(s)
[04:28:13] Skipping subtitle scan.  No suitable subtitle tracks.
[04:28:13] starting job
[04:28:13] work: only 1 chapter, disabling chapter markers
[04:28:13] job configuration:
[04:28:13]  * source
[04:28:13]    + C:\Users\jross\Videos\Steve Dawson - Dawson Forensic Group\Clip0072.MXF
[04:28:13]    + title 3, chapter(s) 1 to 1
[04:28:13]    + container: mxf
[04:28:13]    + data rate: 51371 kbps
[04:28:13]  * destination
[04:28:13]    + C:\Users\jross\Videos\Steve Dawson - Dawson Forensic Group\Clip0072-3.mp4
[04:28:13]    + container: MPEG-4 (libavformat)
[04:28:13]      + align initial A/V stream timestamps
[04:28:13]  * video track
[04:28:13]    + decoder: h264
[04:28:13]    + filters
[04:28:13]      + 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)
[04:28:13]      + Decomb (mode=39)
[04:28:13]      + Framerate Shaper (mode=2:rate=27000000/900000)
[04:28:13]        + frame rate: 14.985 fps -> peak rate limited to 30.000 fps
[04:28:13]      + Crop and Scale (width=1920:height=1080:crop-top=0:crop-bottom=0:crop-left=0:crop-right=0)
[04:28:13]        + source: 1920 * 1080, crop (0/0/0/0): 1920 * 1080, scale: 1920 * 1080
[04:28:13]    + Output geometry
[04:28:13]      + storage dimensions: 1920 x 1080
[04:28:13]      + pixel aspect ratio: 1 : 1
[04:28:13]      + display dimensions: 1920 x 1080
[04:28:13]    + encoder: H.264 (libx264)
[04:28:13]      + preset:  fast
[04:28:13]      + profile: main
[04:28:13]      + level:   4.0
[04:28:13]      + quality: 22.00 (RF)
[04:28:13]      + color profile: 1-11-1
[04:28:13]  * audio track 1
[04:28:13]    + decoder: Unknown (pcm_s24le) (1.0 ch) (1152 kbps) (track 1, id 0x1)
[04:28:13]      + bitrate: 1152 kbps, samplerate: 48000 Hz
[04:28:13]    + mixdown: Mono
[04:28:13]    + dither: none
[04:28:13]    + encoder: AAC (libavcodec)
[04:28:13]      + bitrate: 160 kbps, samplerate: 48000 Hz
[04:28:13] sync: expecting 47820 video frames
[04:28:13] encx264: min-keyint: 15, keyint: 150
[04:28:13] encx264: encoding at constant RF 22.000000
[04:28:13] encx264: unparsed options: level=4.0:ref=2:8x8dct=0:weightp=1:subme=6:vbv-bufsize=25000:vbv-maxrate=20000:rc-lookahead=30
x264 [info]: using SAR=1/1
x264 [info]: using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x264 [info]: profile Main, level 4.0, 4:2:0, 8-bit
[04:28:14] sync: first pts video is 0
[04:28:14] sync: first pts audio 0x1 is 0
[04:28:14] sync: "Chapter 1" (1) at frame 3 time 6006
[08:00:53] reader: done. 1 scr changes
[08:01:03] work: average encoding speed for job is 7.491845 fps
[08:01:04] comb detect: heavy 71079 | light 9615 | uncombed 14946 | total 95640
[08:01:04] decomb: deinterlaced 71079 | blended 9615 | unfiltered 14946 | total 95640
[08:01:04] vfr: 95640 frames output, 0 dropped and 0 duped for CFR/PFR
[08:01:04] vfr: lost time: 0 (0 frames)
[08:01:04] vfr: gained time: 0 (0 frames) (0 not accounted for)
[08:01:04] pcm_s24le-decoder done: 95640 frames, 0 decoder errors
[08:01:04] h264-decoder done: 95640 frames, 0 decoder errors
[08:01:04] sync: got 95640 frames, 47820 expected
[08:01:04] sync: framerate min 14.985 fps, max 29.970 fps, avg 29.970 fps
x264 [info]: frame I:655   Avg QP:18.23  size: 50821
x264 [info]: frame P:24367 Avg QP:21.37  size:  7606
x264 [info]: frame B:70618 Avg QP:21.82  size:  2246
x264 [info]: consecutive B-frames:  1.5%  0.2%  0.2% 98.1%
x264 [info]: mb I  I16..4: 58.9%  0.0% 41.1%
x264 [info]: mb P  I16..4:  0.8%  0.0%  0.1%  P16..4: 20.9%  2.8%  3.5%  0.0%  0.0%    skip:71.9%
x264 [info]: mb B  I16..4:  1.2%  0.0%  0.0%  B16..8:  6.0%  0.6%  0.0%  direct: 7.3%  skip:84.9%  L0:44.6% L1:50.9% BI: 4.5%
x264 [info]: coded y,uvDC,uvAC intra: 20.6% 30.0% 2.7% inter: 1.3% 8.8% 0.0%
x264 [info]: i16 v,h,dc,p: 33% 28% 18% 21%
x264 [info]: i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 33% 15% 30%  4%  5%  4%  4%  3%  3%
x264 [info]: i8c dc,h,v,p: 77% 10% 12%  1%
x264 [info]: Weighted P-Frames: Y:0.0% UV:0.0%
x264 [info]: ref P L0: 62.2% 37.8%
x264 [info]: ref B L0: 72.1% 27.9%
x264 [info]: ref B L1: 91.1%  8.9%
x264 [info]: kb/s:945.73
[08:01:04] mux: track 0, 95640 frames, 377222240 bytes, 945.64 kbps, fifo 4096
[08:01:04] mux: track 1, 149587 frames, 64066718 bytes, 160.61 kbps, fifo 4096
[08:01:04] libhb: work result = 0

# Encode Completed ...



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

Re: Encoding taking extremely long times or not completing - negative numbers showing when converting MXF to MP4

Post by rollin_eng » Tue May 07, 2019 7:38 pm

It’s reading your source as 14.99 fps when it appears to be 30 thus it’s taking twice as long as it’s estimate.

As to why, hopefully a dev will be able to help you out.

Post Reply