log file question

General questions or discussion about HandBrake, Video and/or audio transcoding, trends etc.
Post Reply
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

log file question

Post by xokia »

Description of problem or question:
I had some instances of HB crash a few times with 1.3.3 so I decided to try the nightly build. I turned on more advanced logging. It completed encoding but had some basic questions.

I see some messages about missing frames and a few other messages I'm not used to seeing

[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de6af49400] The cu_qp_delta -37 is outside the valid range [-32, 31].
[truehd @ 000002de6af88040] Substream 0 parity check failed.
[truehd @ 000002de6af88040] Substream 0 checksum failed.

What is the time base in the logfile? Is that wall clock time for the transcoding? Or the time in video itself?

I dont know how to check those specific points in the video. But could those messages be an indication that there is something wrong with the rip from UHD?

HandBrake version (e.g., 1.0.0):
HandBrake Nightly 20201206213608-436cc757f-master



Operating system and version (e.g., Ubuntu 16.04 LTS, macOS 10.13 High Sierra, Windows 10 Creators Update):
windows 10



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

Code: Select all

HandBrake Nightly 20201206213608-436cc757f-master (2020120601)
OS: Microsoft Windows NT 10.0.19042.0
CPU: Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
Ram: 16268 MB, 
GPU Information:
  NVIDIA GeForce GTX 650 - 26.21.14.3200
  Intel(R) HD Graphics 4600 - 20.19.15.4531
Screen: 3840x2160
Temp Dir: C:\Users\AppData\Local\Temp\
Install Dir: C:\Program Files\HandBrake Nightly\
Data Dir: C:\Users\AppData\Roaming\HandBrake\Nightly

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


# Starting Encode ...
[01:15:10] base preset: Fast 1080p30 (Modified)
Remote Process started with Process ID: 4124 and port: 8037
Worker: Starting HandBrake Engine ...
Worker: Starting Web Server on port 8037 ...
Worker: Server Started
Worker: Disconnected worker monitoring enabled!
[01:15:11] Compile-time hardening features are enabled
[01:15:12] hb_init: starting libhb thread
[01:15:12] thread 2 started ("libhb")
[01:15:12] thread 3 started ("work")
[01:15:12] Starting work at: Sat Jan 02 01:15:12 2021
[01:15:12] 1 job(s) to process
[01:15:12] json job:
{
  "Audio": {
    "AudioList": [
      {
        "DRC": 0,
        "Encoder": "copy:truehd",
        "Gain": 0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 0,
        "Name": "Surround 7.1",
        "Track": 0,
        "DitherMethod": 0
      },
      {
        "DRC": 0,
        "Encoder": "copy:ac3",
        "Gain": 0,
        "Mixdown": -1,
        "NormalizeMixLevel": false,
        "Samplerate": 48000,
        "Name": "Surround 5.1",
        "Track": 1,
        "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"
      },
      {
        "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"
      }
    ],
    "ChapterMarkers": true,
    "AlignAVStart": false,
    "File": "K:\\movie\\Terminator- Dark Fate.mkv",
    "Mp4Options": {
      "IpodAtom": false,
      "Mp4Optimize": false
    },
    "Mux": "av_mkv"
  },
  "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": "278",
          "crop-left": "0",
          "crop-right": "0",
          "crop-top": "278",
          "height": "1604",
          "width": "3840"
        }
      },
      {
        "ID": 6,
        "Settings": {
          "mode": "2",
          "rate": "27000000/450000"
        }
      }
    ]
  },
  "PAR": {
    "Num": 1,
    "Den": 1
  },
  "SequenceID": 0,
  "Source": {
    "Angle": 1,
    "Range": {
      "Type": "chapter",
      "Start": 1,
      "End": 20
    },
    "Title": 1,
    "Path": "E:\\video_stream\\Terminator- Dark Fate_t00.mkv"
  },
  "Subtitle": {
    "Search": {
      "Burn": true,
      "Default": false,
      "Enable": true,
      "Forced": true
    },
    "SubtitleList": []
  },
  "Video": {
    "Encoder": "x265_10bit",
    "Level": "auto",
    "TwoPass": false,
    "Turbo": false,
    "ColorMatrixCode": 0,
    "Options": "",
    "Preset": "medium",
    "Profile": "auto",
    "Quality": 22,
    "QSV": {
      "Decode": false
    }
  }
}
[01:15:12] CPU: Intel(R) Core(TM) i7-4770K CPU @ 3.50GHz
[01:15:12]  - Intel microarchitecture Haswell
[01:15:12]  - logical processor count: 8
[01:15:12] Intel Quick Sync Video support: yes, gpu list: 1
[01:15:12]  - Intel Media SDK hardware: API 1.19 (minimum: 1.3)
[01:15:12]  - H.264 encoder: yes
[01:15:12]     - preferred implementation: hardware (any) via D3D11
[01:15:12]     - capabilities (hardware):  breftype icq+la+i+downs vsinfo opt1 opt2+mbbrc+extbrc+trellis+ib_adapt+nmpslice
[01:15:12]  - H.265 encoder: no
[01:15:12] hb_scan: path=E:\video_stream\Terminator- Dark Fate_t00.mkv, title_index=1
[01:15:12] thread 4 started ("scan")
udfread ERROR: ECMA 167 Volume Recognition failed
src/libbluray/disc/disc.c:323: failed opening UDF image E:\video_stream\Terminator- Dark Fate_t00.mkv
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
src/libbluray/bluray.c:2585: nav_get_title_list(E:\video_stream\Terminator- Dark Fate_t00.mkv\) failed
[01:15:12] bd: not a bd - trying as a stream/file instead
libdvdnav: Using dvdnav version 6.0.1
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
[01:15:12] dvd: not a dvd - trying as a stream/file instead
Input #0, matroska,webm, from 'E:\video_stream\Terminator- Dark Fate_t00.mkv':
  Metadata:
    title           : Terminator: Dark Fate
    encoder         : libmakemkv v1.15.4 (1.3.10/1.5.2) win(x64-release)
    creation_time   : 2021-01-01T06:06:12.000000Z
  Duration: 02:08:05.34, start: 0.000000, bitrate: 59134 kb/s
    Chapter #0:0: start 0.000000, end 259.842917
    Metadata:
      title           : Chapter 01
    Chapter #0:1: start 259.842917, end 468.926792
    Metadata:
      title           : Chapter 02
    Chapter #0:2: start 468.926792, end 707.915542
    Metadata:
      title           : Chapter 03
    Chapter #0:3: start 707.915542, end 1016.306958
    Metadata:
      title           : Chapter 04
    Chapter #0:4: start 1016.306958, end 1330.329000
    Metadata:
      title           : Chapter 05
    Chapter #0:5: start 1330.329000, end 1740.613875
    Metadata:
      title           : Chapter 06
    Chapter #0:6: start 1740.613875, end 2005.545208
    Metadata:
      title           : Chapter 07
    Chapter #0:7: start 2005.545208, end 2495.493000
    Metadata:
      title           : Chapter 08
    Chapter #0:8: start 2495.493000, end 2822.694875
    Metadata:
      title           : Chapter 09
    Chapter #0:9: start 2822.694875, end 3188.935750
    Metadata:
      title           : Chapter 10
    Chapter #0:10: start 3188.935750, end 3476.806667
    Metadata:
      title           : Chapter 11
    Chapter #0:11: start 3476.806667, end 3902.690458
    Metadata:
      title           : Chapter 12
    Chapter #0:12: start 3902.690458, end 4387.674958
    Metadata:
      title           : Chapter 13
    Chapter #0:13: start 4387.674958, end 4846.049542
    Metadata:
      title           : Chapter 14
    Chapter #0:14: start 4846.049542, end 5249.410833
    Metadata:
      title           : Chapter 15
    Chapter #0:15: start 5249.410833, end 5568.563000
    Metadata:
      title           : Chapter 16
    Chapter #0:16: start 5568.563000, end 6105.641208
    Metadata:
      title           : Chapter 17
    Chapter #0:17: start 6105.641208, end 6575.318750
    Metadata:
      title           : Chapter 18
    Chapter #0:18: start 6575.318750, end 7078.988583
    Metadata:
      title           : Chapter 19
    Chapter #0:19: start 7078.988583, end 7685.344333
    Metadata:
      title           : Chapter 20
    Stream #0:0(eng): Video: hevc (Main 10), yuv420p10le(tv, bt2020nc/bt2020/smpte2084), 3840x2160 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 23.98 tbc
    Metadata:
      BPS-eng         : 53847069
      DURATION-eng    : 02:08:05.344333333
      NUMBER_OF_FRAMES-eng: 184264
      NUMBER_OF_BYTES-eng: 51729156897
      SOURCE_ID-eng   : 001011
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.15.4 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2021-01-01 06:06:12
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
    Stream #0:1(eng): Audio: truehd, 48000 Hz, 7.1, s32 (24 bit) (default)
    Metadata:
      title           : Surround 7.1
      BPS-eng         : 4633701
      DURATION-eng    : 02:08:05.345000000
      NUMBER_OF_FRAMES-eng: 9222414
      NUMBER_OF_BYTES-eng: 4451449046
      SOURCE_ID-eng   : 001100
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.15.4 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2021-01-01 06:06:12
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
    Stream #0:2(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s
    Metadata:
      title           : Surround 5.1
      BPS-eng         : 640000
      DURATION-eng    : 02:08:05.376000000
      NUMBER_OF_FRAMES-eng: 240168
      NUMBER_OF_BYTES-eng: 614830080
      SOURCE_ID-eng   : 001100
      _STATISTICS_WRITING_APP-eng: MakeMKV v1.15.4 win(x64-release)
      _STATISTICS_WRITING_DATE_UTC-eng: 2021-01-01 06:06:12
      _STATISTICS_TAGS-eng: BPS DURATION NUMBER_OF_FRAMES NUMBER_OF_BYTES SOURCE_ID
[01:15:12] Found ffmpeg 20 chapters, container=matroska,webm
[01:15:12] Added chapter 1, name='Chapter 1', dur=23385862, (00:04:20)
[01:15:12] Added chapter 2, name='Chapter 2', dur=18817549, (00:03:29)
[01:15:12] Added chapter 3, name='Chapter 3', dur=21508987, (00:03:59)
[01:15:12] Added chapter 4, name='Chapter 4', dur=27755228, (00:05:08)
[01:15:12] Added chapter 5, name='Chapter 5', dur=28261984, (00:05:14)
[01:15:12] Added chapter 6, name='Chapter 6', dur=36925638, (00:06:50)
[01:15:12] Added chapter 7, name='Chapter 7', dur=23843820, (00:04:25)
[01:15:12] Added chapter 8, name='Chapter 8', dur=44095302, (00:08:10)
[01:15:12] Added chapter 9, name='Chapter 9', dur=29448168, (00:05:27)
[01:15:12] Added chapter 10, name='Chapter 10', dur=32961679, (00:06:06)
[01:15:12] Added chapter 11, name='Chapter 11', dur=25908382, (00:04:48)
[01:15:12] Added chapter 12, name='Chapter 12', dur=38329542, (00:07:06)
[01:15:12] Added chapter 13, name='Chapter 13', dur=43648605, (00:08:05)
[01:15:12] Added chapter 14, name='Chapter 14', dur=41253712, (00:07:38)
[01:15:12] Added chapter 15, name='Chapter 15', dur=36302516, (00:06:43)
[01:15:12] Added chapter 16, name='Chapter 16', dur=28723696, (00:05:19)
[01:15:12] Added chapter 17, name='Chapter 17', dur=48337038, (00:08:57)
[01:15:12] Added chapter 18, name='Chapter 18', dur=42270979, (00:07:50)
[01:15:12] Added chapter 19, name='Chapter 19', dur=45330285, (00:08:24)
[01:15:12] Added chapter 20, name='Chapter 20', dur=54571988, (00:10:06)
[01:15:12] scan: decoding previews for title 1
[01:15:12] scan: preview 1
[01:15:12] fifo_close: trashing 0 buffer(s)
[01:15:12] scan: audio 0x2: ac3, rate=48000Hz, bitrate=640000 English (AC3) (5.1 ch) (Dolby Digital EX) (640 kbps)
[01:15:12] fifo_close: trashing 0 buffer(s)
[01:15:12] scan: audio 0x1: truehd, rate=48000Hz, bitrate=1 English (TrueHD) (7.1 ch)
[01:15:12] scan: preview 2
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:13] scan: preview 3
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:13] scan: preview 4
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:13] scan: preview 5
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:14] scan: preview 6
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:14] scan: preview 7
[01:15:14] scan: preview 8
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:15] scan: preview 9
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:15] scan: preview 10
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de713d6500] First slice in a frame missing.
[01:15:16] scan: 10 previews, 3840x2160, 23.976 fps, autocrop = 278/278/0/0, aspect 16:9, PAR 1:1
[01:15:16] scan: supported video decoders: avcodec qsv
[01:15:16] Freed 1 buffers of size 1024
[01:15:16] Freed 1 buffers of size 2048
[01:15:16] Freed 1 buffers of size 4096
[01:15:16] Freed 1 buffers of size 8192
[01:15:16] Freed 1 buffers of size 131072
[01:15:16] Freed 1 buffers of size 262144
[01:15:16] Freed 1 buffers of size 524288
[01:15:16] Freed 1 buffers of size 1048576
[01:15:16] Freed 1 buffers of size 2097152
[01:15:16] Freed 2 buffers of size 16777216
[01:15:16] Allocated 37633024 bytes of buffers on this pass and Freed 37633024 bytes, 0 bytes leaked
[01:15:16] thread 4 exited ("scan")
[01:15:16] thread 4 joined ("scan")
[01:15:16] libhb: scan thread found 1 valid title(s)
[01:15:16] Adding subtitle scan pass
[01:15:16] Skipping subtitle scan.  No suitable subtitle tracks.
[01:15:16] Starting Task: Encoding Pass
[01:15:16] thread 5 started ("decomb_filter_segment")
[01:15:16] thread 6 started ("decomb_filter_segment")
[01:15:16] thread 7 started ("decomb_filter_segment")
[01:15:16] thread 8 started ("decomb_filter_segment")
[01:15:16] thread 9 started ("decomb_filter_segment")
[01:15:16] thread a started ("decomb_filter_segment")
[01:15:16] thread b started ("decomb_filter_segment")
[01:15:16] thread c started ("decomb_filter_segment")
[01:15:16] thread d started ("decomb_check_segment")
[01:15:16] thread e started ("decomb_check_segment")
[01:15:16] thread f started ("decomb_check_segment")
[01:15:16] thread 10 started ("decomb_check_segment")
[01:15:16] thread 11 started ("decomb_check_segment")
[01:15:16] thread 12 started ("decomb_check_segment")
[01:15:16] thread 13 started ("decomb_check_segment")
[01:15:16] thread 14 started ("decomb_check_segment")
[01:15:16] thread 15 started ("mask_filter_segment")
[01:15:16] thread 16 started ("mask_filter_segment")
[01:15:16] thread 17 started ("mask_filter_segment")
[01:15:16] thread 18 started ("mask_filter_segment")
[01:15:16] thread 19 started ("mask_filter_segment")
[01:15:16] thread 1a started ("mask_filter_segment")
[01:15:16] thread 1b started ("mask_filter_segment")
[01:15:16] thread 1c started ("mask_filter_segment")
[01:15:16] thread 1d started ("mask_erode_segment")
[01:15:16] thread 1e started ("mask_erode_segment")
[01:15:16] thread 1f started ("mask_erode_segment")
[01:15:16] thread 20 started ("mask_erode_segment")
[01:15:16] thread 21 started ("mask_erode_segment")
[01:15:16] thread 22 started ("mask_erode_segment")
[01:15:16] thread 23 started ("mask_erode_segment")
[01:15:16] thread 24 started ("mask_erode_segment")
[01:15:16] thread 25 started ("mask_dilate_segment")
[01:15:16] thread 26 started ("mask_dilate_segment")
[01:15:16] thread 27 started ("mask_dilate_segment")
[01:15:16] thread 28 started ("mask_dilate_segment")
[01:15:16] thread 29 started ("mask_dilate_segment")
[01:15:16] thread 2a started ("mask_dilate_segment")
[01:15:16] thread 2b started ("mask_dilate_segment")
[01:15:16] thread 2c started ("mask_dilate_segment")
[01:15:16] thread 2d started ("yadif_filter_segment")
[01:15:16] thread 2e started ("yadif_filter_segment")
[01:15:16] thread 2f started ("yadif_filter_segment")
[01:15:16] thread 30 started ("yadif_filter_segment")
[01:15:16] thread 31 started ("yadif_filter_segment")
[01:15:16] thread 32 started ("yadif_filter_segment")
[01:15:16] thread 33 started ("yadif_filter_segment")
[01:15:16] thread 34 started ("yadif_filter_segment")
[01:15:16] job configuration:
[01:15:16]  * source
[01:15:16]    + E:\video_stream\Terminator- Dark Fate_t00.mkv
[01:15:16]    + title 1, chapter(s) 1 to 20
[01:15:16]    + container: matroska,webm
[01:15:16]    + data rate: 59134 kbps
[01:15:16]  * destination
[01:15:16]    + K:\movie\Terminator- Dark Fate.mkv
[01:15:16]    + container: Matroska (libavformat)
[01:15:16]      + chapter markers
[01:15:16]  * video track
[01:15:16]    + decoder: hevc 8-bit
[01:15:16]    + filters
[01:15:16]      + 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)
[01:15:16]      + Decomb (mode=39)
[01:15:16]      + Framerate Shaper (mode=2:rate=27000000/450000)
[01:15:16]        + frame rate: 23.976 fps -> peak rate limited to 60.000 fps
[01:15:16]      + AVFilter (crop='x=0:y=278:w=3840:h=1604',scale='width=3840:height=1604:flags=lanczos+accurate_rnd:in_color_matrix=bt2020:out_color_matrix=bt2020:out_range=limited',format='pix_fmts=yuv420p')
[01:15:16]        + crop: x=0, y=278, w=3840, h=1604
[01:15:16]        + scale: width=3840, height=1604, flags=lanczos+accurate_rnd, in_color_matrix=bt2020, out_color_matrix=bt2020, out_range=limited
[01:15:16]        + format: pix_fmts=yuv420p
[01:15:16]      + Crop and Scale (width=3840:height=1604:crop-top=278:crop-bottom=278:crop-left=0:crop-right=0)
[01:15:16]        + source: 3840 * 2160, crop (278/278/0/0): 3840 * 1604, scale: 3840 * 1604
[01:15:16]    + Output geometry
[01:15:16]      + storage dimensions: 3840 x 1604
[01:15:16]      + pixel aspect ratio: 1 : 1
[01:15:16]      + display dimensions: 3840 x 1604
[01:15:16]    + encoder: H.265 10-bit (libx265)
[01:15:16]      + preset:  medium
[01:15:16]      + profile: auto
[01:15:16]      + level:   auto
[01:15:16]      + quality: 22.00 (RF)
[01:15:16]      + color profile: 9-16-9
[01:15:16]  * audio track 1
[01:15:16]    + name: Surround 7.1
[01:15:16]    + decoder: English (TrueHD) (7.1 ch) (track 1, id 0x1)
[01:15:16]      + samplerate: 48000 Hz
[01:15:16]    + TrueHD Passthru
[01:15:16]  * audio track 2
[01:15:16]    + name: Surround 5.1
[01:15:16]    + decoder: English (AC3) (5.1 ch) (Dolby Digital EX) (640 kbps) (track 2, id 0x2)
[01:15:16]      + bitrate: 640 kbps, samplerate: 48000 Hz
[01:15:16]    + AC3 Passthru
[01:15:16] sync: expecting 184263 video frames
[01:15:16] thread 35 started ("Audio Synchronization")
[01:15:16] thread 36 started ("Audio Synchronization")
x265 [info]: HEVC encoder version 3.2.1+1-b5c86a64bbbe
x265 [info]: build info [Windows][GCC 9.2.0][64 bit] 10bit
x265 [info]: using cpu capabilities: MMX2 SSE2Fast LZCNT SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
x265 [info]: Main 10 profile, Level-5 (Main tier)
x265 [info]: Thread pool created using 8 threads
x265 [info]: Slices                              : 1
x265 [info]: frame threads / pool features       : 3 / wpp(26 rows)
x265 [info]: Coding QT: max CU size, min CU size : 64 / 8
x265 [info]: Residual QT: max TU size, max depth : 32 / 1 inter / 1 intra
x265 [info]: ME / range / subpel / merge         : hex / 57 / 2 / 3
x265 [info]: Keyframe min / max / scenecut / bias: 24 / 240 / 40 / 5.00
x265 [info]: Lookahead / bframes / badapt        : 20 / 4 / 2
x265 [info]: b-pyramid / weightp / weightb       : 1 / 1 / 0
x265 [info]: References / ref-limit  cu / depth  : 3 / off / on
x265 [info]: AQ: mode / str / qg-size / cu-tree  : 2 / 1.0 / 32 / 1
x265 [info]: Rate Control / qCompress            : CRF-22.0 / 0.60
x265 [info]: tools: rd=3 psy-rd=2.00 early-skip rskip signhide tmvp b-intra
x265 [info]: tools: strong-intra-smoothing lslices=8 deblock sao
[01:15:16] Writing Metadata to output file...
[01:15:16] thread 37 started ("Muxer")
[01:15:16] thread 38 started ("Muxer")
[01:15:16] thread 39 started ("Reader")
[01:15:16] thread 3a started ("Audio decoder (libavcodec)")
[01:15:16] ffmpeg_read starting chapter 1 at 0
[01:15:16] thread 3b started ("Audio decoder (libavcodec)")
[01:15:16] thread 3c started ("Video decoder (libavcodec)")
[01:15:16] thread 3d started ("Video Synchronization")
[01:15:16] thread 3e started ("H.265/HEVC encoder (libx265)")
[01:15:16] thread 3f started ("Muxer")
[01:15:16] thread 40 started ("Comb Detect")
[01:15:16] thread 41 started ("Decomb")
[01:15:16] thread 42 started ("Framerate Shaper")
[01:15:16] thread 43 started ("AVFilter")
[01:15:16] sync: first pts audio 0x1 is 810
[01:15:16] sync: first pts audio 0x2 is 0
[01:15:16] sync: first pts video is 0
[01:15:16] sync: "Chapter 1" (1) at frame 1 time 0
[01:53:34] ffmpeg_read starting chapter 2 at 23385870
[01:53:48] sync: "Chapter 2" (2) at frame 6231 time 23385862
[02:17:09] ffmpeg_read starting chapter 3 at 42203430
[02:17:32] sync: "Chapter 3" (3) at frame 11244 time 42203411
[02:44:46] ffmpeg_read starting chapter 4 at 63712440
[02:44:59] sync: "Chapter 4" (4) at frame 16974 time 63712398
[03:23:41] ffmpeg_read starting chapter 5 at 91467630
[03:23:57] sync: "Chapter 5" (5) at frame 24368 time 91467626
[hevc @ 000002de6af49400] The cu_qp_delta -37 is outside the valid range [-32, 31].
[04:11:59] ffmpeg_read starting chapter 6 at 119729610
[04:12:21] sync: "Chapter 6" (6) at frame 31897 time 119729610
[hevc @ 000002de6af48b00] The cu_qp_delta 32 is outside the valid range [-32, 31].
[05:01:55] ffmpeg_read starting chapter 7 at 156655260
[05:02:07] sync: "Chapter 7" (7) at frame 41734 time 156655248
[05:33:34] ffmpeg_read starting chapter 8 at 180510300
[05:33:45] sync: "Chapter 8" (8) at frame 48089 time 180510330
[hevc @ 000002de6af48b00] The cu_qp_delta 33 is outside the valid range [-32, 31].
[06:24:00] ffmpeg_read starting chapter 9 at 224594370
[06:24:16] sync: "Chapter 9" (9) at frame 59833 time 224594370
[07:02:05] ffmpeg_read starting chapter 10 at 254042550
[07:02:34] sync: "Chapter 10" (10) at frame 67678 time 254042538
[07:35:38] ffmpeg_read starting chapter 11 at 287004240
[07:35:46] sync: "Chapter 11" (11) at frame 76459 time 287004217
[truehd @ 000002de6af88040] Substream 0 parity check failed.
[truehd @ 000002de6af88040] Substream 0 checksum failed.
[hevc @ 000002de6aee1b00] The cu_qp_delta 76 is outside the valid range [-32, 31].
[08:05:42] ffmpeg_read starting chapter 12 at 312912630
[08:06:01] sync: "Chapter 12" (12) at frame 83361 time 312912600
[08:59:19] ffmpeg_read starting chapter 13 at 351253440
[08:59:29] sync: "Chapter 13" (13) at frame 93575 time 351253402
[09:41:27] ffmpeg_read starting chapter 14 at 394890750
[09:41:40] sync: "Chapter 14" (14) at frame 105200 time 394890746
[10:23:14] ffmpeg_read starting chapter 15 at 436144500
[10:23:35] sync: "Chapter 15" (15) at frame 116190 time 436144458
[11:06:17] ffmpeg_read starting chapter 16 at 472446990
[11:06:31] sync: "Chapter 16" (16) at frame 125861 time 472446975
[11:31:50] ffmpeg_read starting chapter 17 at 501170670
[11:32:03] sync: "Chapter 17" (17) at frame 133513 time 501170670
[12:46:21] ffmpeg_read starting chapter 18 at 549518940
[12:46:35] sync: "Chapter 18" (18) at frame 146393 time 549518970
[hevc @ 000002de715b7480] The cu_qp_delta 40 is outside the valid range [-32, 31].
[13:40:51] ffmpeg_read starting chapter 19 at 591778710
[13:41:19] sync: "Chapter 19" (19) at frame 157651 time 591778687
[hevc @ 000002de6af48b00] The cu_qp_delta -54 is outside the valid range [-32, 31].
[14:51:27] ffmpeg_read starting chapter 20 at 637109010
[14:51:37] sync: "Chapter 20" (20) at frame 169727 time 637108972
[15:26:38] reader: done. 1 scr changes
[15:26:38] thread 39 exited ("Reader")
[15:27:01] thread 3f exited ("Muxer")
[15:27:01] thread 3f joined ("Muxer")
[15:27:01] work: average encoding speed for job is 3.606158 fps
[15:27:01] thread 3e exited ("H.265/HEVC encoder (libx265)")
[15:27:01] thread 35 exited ("Audio Synchronization")
[15:27:01] thread 36 exited ("Audio Synchronization")
[15:27:01] thread 3a exited ("Audio decoder (libavcodec)")
[15:27:01] thread 43 exited ("AVFilter")
[15:27:01] thread 42 exited ("Framerate Shaper")
[15:27:01] thread 3d exited ("Video Synchronization")
[15:27:01] thread 41 exited ("Decomb")
[15:27:01] thread 40 exited ("Comb Detect")
[15:27:01] thread 38 exited ("Muxer")
[15:27:01] thread 3b exited ("Audio decoder (libavcodec)")
[15:27:01] thread 37 exited ("Muxer")
[15:27:01] thread 3c exited ("Video decoder (libavcodec)")
[15:27:01] thread 40 joined ("Comb Detect")
[15:27:01] comb detect: heavy 16595 | light 7723 | uncombed 159946 | total 184264
[15:27:01] thread b exited ("decomb_filter_segment")
[15:27:01] thread 5 exited ("decomb_filter_segment")
[15:27:01] thread c exited ("decomb_filter_segment")
[15:27:01] thread 6 exited ("decomb_filter_segment")
[15:27:01] thread 9 exited ("decomb_filter_segment")
[15:27:01] thread 8 exited ("decomb_filter_segment")
[15:27:01] thread 7 exited ("decomb_filter_segment")
[15:27:01] thread a exited ("decomb_filter_segment")
[15:27:01] thread 5 joined ("decomb_filter_segment")
[15:27:01] thread 6 joined ("decomb_filter_segment")
[15:27:01] thread 7 joined ("decomb_filter_segment")
[15:27:01] thread 8 joined ("decomb_filter_segment")
[15:27:01] thread 9 joined ("decomb_filter_segment")
[15:27:01] thread a joined ("decomb_filter_segment")
[15:27:01] thread b joined ("decomb_filter_segment")
[15:27:01] thread c joined ("decomb_filter_segment")
[15:27:01] thread e exited ("decomb_check_segment")
[15:27:01] thread f exited ("decomb_check_segment")
[15:27:01] thread 11 exited ("decomb_check_segment")
[15:27:01] thread 14 exited ("decomb_check_segment")
[15:27:01] thread 10 exited ("decomb_check_segment")
[15:27:01] thread d exited ("decomb_check_segment")
[15:27:01] thread 13 exited ("decomb_check_segment")
[15:27:01] thread 12 exited ("decomb_check_segment")
[15:27:01] thread d joined ("decomb_check_segment")
[15:27:01] thread e joined ("decomb_check_segment")
[15:27:01] thread f joined ("decomb_check_segment")
[15:27:01] thread 10 joined ("decomb_check_segment")
[15:27:01] thread 11 joined ("decomb_check_segment")
[15:27:01] thread 12 joined ("decomb_check_segment")
[15:27:01] thread 13 joined ("decomb_check_segment")
[15:27:01] thread 14 joined ("decomb_check_segment")
[15:27:01] thread 17 exited ("mask_filter_segment")
[15:27:01] thread 1b exited ("mask_filter_segment")
[15:27:01] thread 15 exited ("mask_filter_segment")
[15:27:01] thread 16 exited ("mask_filter_segment")
[15:27:01] thread 18 exited ("mask_filter_segment")
[15:27:01] thread 1a exited ("mask_filter_segment")
[15:27:01] thread 15 joined ("mask_filter_segment")
[15:27:01] thread 19 exited ("mask_filter_segment")
[15:27:01] thread 1c exited ("mask_filter_segment")
[15:27:01] thread 16 joined ("mask_filter_segment")
[15:27:01] thread 17 joined ("mask_filter_segment")
[15:27:01] thread 18 joined ("mask_filter_segment")
[15:27:01] thread 19 joined ("mask_filter_segment")
[15:27:01] thread 1a joined ("mask_filter_segment")
[15:27:01] thread 1b joined ("mask_filter_segment")
[15:27:01] thread 1c joined ("mask_filter_segment")
[15:27:01] thread 1d exited ("mask_erode_segment")
[15:27:01] thread 23 exited ("mask_erode_segment")
[15:27:01] thread 20 exited ("mask_erode_segment")
[15:27:01] thread 24 exited ("mask_erode_segment")
[15:27:01] thread 21 exited ("mask_erode_segment")
[15:27:01] thread 22 exited ("mask_erode_segment")
[15:27:01] thread 1f exited ("mask_erode_segment")
[15:27:01] thread 1e exited ("mask_erode_segment")
[15:27:01] thread 1d joined ("mask_erode_segment")
[15:27:01] thread 1e joined ("mask_erode_segment")
[15:27:01] thread 1f joined ("mask_erode_segment")
[15:27:01] thread 20 joined ("mask_erode_segment")
[15:27:01] thread 21 joined ("mask_erode_segment")
[15:27:01] thread 22 joined ("mask_erode_segment")
[15:27:01] thread 23 joined ("mask_erode_segment")
[15:27:01] thread 24 joined ("mask_erode_segment")
[15:27:01] thread 25 exited ("mask_dilate_segment")
[15:27:01] thread 2c exited ("mask_dilate_segment")
[15:27:01] thread 2b exited ("mask_dilate_segment")
[15:27:01] thread 27 exited ("mask_dilate_segment")
[15:27:01] thread 2a exited ("mask_dilate_segment")
[15:27:01] thread 28 exited ("mask_dilate_segment")
[15:27:01] thread 29 exited ("mask_dilate_segment")
[15:27:01] thread 25 joined ("mask_dilate_segment")
[15:27:01] thread 26 exited ("mask_dilate_segment")
[15:27:01] thread 26 joined ("mask_dilate_segment")
[15:27:01] thread 27 joined ("mask_dilate_segment")
[15:27:01] thread 28 joined ("mask_dilate_segment")
[15:27:01] thread 29 joined ("mask_dilate_segment")
[15:27:01] thread 2a joined ("mask_dilate_segment")
[15:27:01] thread 2b joined ("mask_dilate_segment")
[15:27:01] thread 2c joined ("mask_dilate_segment")
[15:27:01] thread 41 joined ("Decomb")
[15:27:01] decomb: deinterlaced 16595 | blended 7723 | unfiltered 159946 | total 184264
[15:27:01] thread 2d exited ("yadif_filter_segment")
[15:27:01] thread 34 exited ("yadif_filter_segment")
[15:27:01] thread 32 exited ("yadif_filter_segment")
[15:27:01] thread 31 exited ("yadif_filter_segment")
[15:27:01] thread 2e exited ("yadif_filter_segment")
[15:27:01] thread 33 exited ("yadif_filter_segment")
[15:27:01] thread 30 exited ("yadif_filter_segment")
[15:27:01] thread 2f exited ("yadif_filter_segment")
[15:27:01] thread 2d joined ("yadif_filter_segment")
[15:27:01] thread 2e joined ("yadif_filter_segment")
[15:27:01] thread 2f joined ("yadif_filter_segment")
[15:27:01] thread 30 joined ("yadif_filter_segment")
[15:27:01] thread 31 joined ("yadif_filter_segment")
[15:27:01] thread 32 joined ("yadif_filter_segment")
[15:27:01] thread 33 joined ("yadif_filter_segment")
[15:27:01] thread 34 joined ("yadif_filter_segment")
[15:27:01] thread 42 joined ("Framerate Shaper")
[15:27:01] vfr: 184264 frames output, 0 dropped and 0 duped for CFR/PFR
[15:27:01] vfr: lost time: 0 (0 frames)
[15:27:01] vfr: gained time: 0 (0 frames) (0 not accounted for)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] thread 43 joined ("AVFilter")
[15:27:01] thread 39 joined ("Reader")
[15:27:01] thread 3a joined ("Audio decoder (libavcodec)")
[15:27:01] thread 3b joined ("Audio decoder (libavcodec)")
[15:27:01] thread 3c joined ("Video decoder (libavcodec)")
[15:27:01] thread 3d joined ("Video Synchronization")
[15:27:01] thread 3e joined ("H.265/HEVC encoder (libx265)")
[15:27:01] truehd-decoder done: 9222414 frames, 0 decoder errors
[15:27:01] ac3-decoder done: 240168 frames, 0 decoder errors
[15:27:01] hevc-decoder done: 184264 frames, 0 decoder errors
[15:27:01] sync: got 184264 frames, 184263 expected
[15:27:01] sync: framerate min 23.976 fps, max 23.976 fps, avg 23.976 fps
[15:27:01] thread 35 joined ("Audio Synchronization")
[15:27:01] thread 36 joined ("Audio Synchronization")
x265 [info]: frame I:   1981, Avg QP:21.41  kb/s: 17820.88
x265 [info]: frame P:  47600, Avg QP:22.77  kb/s: 6702.38
x265 [info]: frame B: 134683, Avg QP:26.95  kb/s: 1953.00
x265 [info]: Weighted P-Frames: Y:7.8% UV:6.0%
x265 [info]: consecutive B-frames: 10.9% 15.6% 8.8% 20.3% 44.4%
encoded 184264 frames in 51105.48s (3.61 fps), 3350.48 kb/s, Avg QP:25.81
[15:27:01] mux: file size, 8353399065 bytes
[15:27:01] mux: track 0, 184264 frames, 3219435561 bytes, 3351.21 kbps, fifo 1024
[15:27:01] mux: track 1, 9222414 frames, 4451449046 bytes, 4633.65 kbps, fifo 32768
[15:27:01] mux: track 2, 240168 frames, 614830080 bytes, 640.00 kbps, fifo 1024
[15:27:01] mux: overhead, 7.02 bytes per frame
[15:27:01] thread 37 joined ("Muxer")
[15:27:01] thread 38 joined ("Muxer")
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] fifo_close: trashing 0 buffer(s)
[15:27:01] Freed 320 buffers of size 1024
[15:27:01] Freed 32 buffers of size 2048
[15:27:01] Freed 32 buffers of size 4096
[15:27:01] Freed 32 buffers of size 8192
[15:27:01] Freed 32 buffers of size 16384
[15:27:01] Freed 32 buffers of size 32768
[15:27:01] Freed 32 buffers of size 65536
[15:27:01] Freed 32 buffers of size 131072
[15:27:01] Freed 32 buffers of size 262144
[15:27:01] Freed 25 buffers of size 524288
[15:27:01] Freed 8 buffers of size 1048576
[15:27:01] Freed 3 buffers of size 2097152
[15:27:02] Freed 32 buffers of size 16777216
[15:27:02] Allocated 581697536 bytes of buffers on this pass and Freed 581697536 bytes, 0 bytes leaked
[15:27:02] Finished work at: Sat Jan 02 15:27:02 2021
[15:27:02] thread 3 exited ("work")
[15:27:02] thread 3 joined ("work")
[15:27:02] libhb: work result = 0

# Job Completed!

Deleted User 11865

Re: log file question

Post by Deleted User 11865 »

xokia wrote: Sun Jan 03, 2021 12:02 am [hevc @ 000002de713d6500] First slice in a frame missing.
[hevc @ 000002de6af49400] The cu_qp_delta -37 is outside the valid range [-32, 31].
[truehd @ 000002de6af88040] Substream 0 parity check failed.
[truehd @ 000002de6af88040] Substream 0 checksum failed.

What is the time base in the logfile? Is that wall clock time for the transcoding? Or the time in video itself?
Those are memory addresses, not timestamps. Such messages before the job configuration (i.e. during the scan phase) can be safely ignored; during the main encode, they could indicate a bad rip or sometimes possibly decoder bugs.

FWIW, I always remux my MakeMKV rips with mkvmerge or mkvtoolnix GUI just in case -- there rarely are any issues, but it makes me feel safer :D
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

I knew those were memory addresses. I was referring to the times posted in the log before those messages. I was trying to go to those times in the video to see if I could find anything wrong but I can’t figure out the time base used in the logs.

When I played the video back I noticed the audio was a few milliseconds ahead of the video. So I suspect maybe I had a bad rip. I reripped the video and will try encoding again.

Does remuxing report errors if it finds them? I’ll have to check out those tools.

Thanks for the help!
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

Checked some other videos noticed audio is also off a little bit. I am doing h.265 in mks container and pass through audio.

I noticed mp4 had a box to align video and audio. Mks container does not have this option. Is there a work around?
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

I checked out that mkvtoolnix and looks like it allows me to adjust the audio. Thank you for the recommendation! Would be nice if handbrake supported this during the encode so there wasnt an extra step.
Deleted User 11865

Re: log file question

Post by Deleted User 11865 »

xokia wrote: Sun Jan 03, 2021 4:33 am I knew those were memory addresses. I was referring to the times posted in the log before those messages.
Local time, [hh:mm:ss] :D

Code: Select all

[01:15:16] sync: first pts audio 0x1 is 810
[01:15:16] sync: first pts audio 0x2 is 0
[01:15:16] sync: first pts video is 0
[01:15:16] sync: "Chapter 1" (1) at frame 1 time 0
[01:53:34] ffmpeg_read starting chapter 2 at 23385870
Those times are on a 90 kHz timebase.
Deleted User 11865

Re: log file question

Post by Deleted User 11865 »

xokia wrote: Sun Jan 03, 2021 9:25 am I checked out that mkvtoolnix and looks like it allows me to adjust the audio. Thank you for the recommendation! Would be nice if handbrake supported this during the encode so there wasnt an extra step.
We try to get the A/V sync right rather than letting the user adjust it. Are you usaying the audio was in-sync before HandBrake and out-of-sync after the encode?
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

Rodeo wrote: Sun Jan 03, 2021 1:48 pm
xokia wrote: Sun Jan 03, 2021 9:25 am I checked out that mkvtoolnix and looks like it allows me to adjust the audio. Thank you for the recommendation! Would be nice if handbrake supported this during the encode so there wasnt an extra step.
We try to get the A/V sync right rather than letting the user adjust it. Are you usaying the audio was in-sync before HandBrake and out-of-sync after the encode?
I reripped the MKV and those errors went away but I still have issues with audio syncing.

The original video is from my UHD disc. I can’t stream the ripped mkv. It sorta works but I’m using a 4K firestick and it doesn’t keep up with the decode of the original source.

I also have 2nd gen fireTV devices and audio seems to be in sync on those. But for the 4K firestick it’s out of sync by about 200ms. I have another tv with a 4K firestick I’ll do some playing around with and see if I can tell if it’s a source or device issues. Kodi has an option to shift the audio so not a huge deal.
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

So issue also seems to be on 2nd gen firetv. 200ms is hard to see you have to see enough dialog with lips moving to see it. Also appears to be 200ms off.

I also have powerDVD I’ll try streaming from my PC to the TVs to see if anything changes. I also have some chromecast I’ll try streaming the video to.
Deleted User 11865

Re: log file question

Post by Deleted User 11865 »

Well, maybe try playing the source MKV rip locally first with e.g. VLC, to see if it's out of sync to begin with?
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

Rodeo wrote: Sun Jan 03, 2021 11:43 pm Well, maybe try playing the source MKV rip locally first with e.g. VLC, to see if it's out of sync to begin with?
No desktop speakers and computer is tied up encoding.
I’ll try and find some speakers when it gets done with its current encode.
xokia
Posts: 38
Joined: Wed Dec 23, 2020 9:50 pm

Re: log file question

Post by xokia »

Just replying to this thread for the errors in the log file the audio I fixed via kodi and adjusting the timing.
[hevc @ 000002de6af49400] The cu_qp_delta -37 is outside the valid range [-32, 31].

These were valid errors. These would result in artifacts in the video encoding. Looks like I had some issues with my memory modules that handbrake stressed enough and caused to fail. After encoding about 30 movies my machine would randomly crash, I never had an issue with this machine before handbrake. Obviously not handbrakes fault. Handbrake just stresses the machine enough that if there are any weak points they will show.

I was able to run a memory test on my machine and found a range of addresses that were bad. This machine is running DDR3 so I bumped the ram voltage from 1.5 to 1.65v ran the same RAM stress test and the errors are gone. Also further Handbrake encodings no longer report errors. Just FYI. Log files is very useful
Post Reply