This forum uses cookies
This forum makes use of cookies to store your login information if you are registered, and your last visit if you are not. Cookies are small text documents stored on your computer; the cookies set by this forum can only be used on this website and pose no security risk. Cookies on this forum also track the specific topics you have read and when you last read them. Please confirm whether you accept or reject these cookies being set.

A cookie will be stored in your browser regardless of choice to prevent you being asked this question again. You will be able to change your cookie settings at any time using the link in the footer.

[BUG] Timecode Problem with Cut Support
#7
For the future: Do not post a debug output containing the processing of multiple jobs, since I have not clue which part of the debug output is important I have to read the whole thing which really takes a lot of my free time.
So if you do it again, do not be surprised then I simply tell you to fuck off.  Big Grin


Looking at "HybridDebugOutput vfr to cfr, inconsistent.txt"

Looking at the processing of job 2021-03-16@13_50_54_8110:
1st subjob processed is 2021-03-16@13_50_54_8110_01_audio which extracts the audio using:
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:0 -vn -sn -t 00:00:05.666 -acodec copy -map_metadata -1 -metadata encoding_tool="Hybrid 2021.03.14.1" "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_50_54_8110_01.aac"
it finished after 00:00:00.254 and created C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_50_54_8110_01.aac (0.118719 MB)
2nd job is 2021-03-16@13_50_54_8110_02_video which encodes the video using: (line 75632)
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -loglevel fatal -noautorotate -nostdin -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:1 -an -sn -t 00:00:05.666 -pix_fmt yuv420p -vsync 0 -f rawvideo - | "C:\Program Files\Hybrid\64bit\x264.exe" --preset veryslow --crf 19.50 --profile high --level 4.2 --ref 5 --keyint 600 --min-keyint 30 --b-adapt 1 --sync-lookahead 1 --rc-lookahead 40 --no-fast-pskip --subme 7 --aq-mode 2 --threads 1 --sar 1:1 --non-deterministic --demuxer raw --input-res 1920x1080 --input-csp i420 --input-depth 8 --fps 60/1 --stitchable --output-depth 8 --output "C:\Users\User\AppData\Local\Temp\2021-03-16@13_50_54_8110_02.264" -
in the middle of the video encoding a new job is created which really helps reading the debug output.  Angry
encoding finished after 00:01:28.680, encoded 340 frames, 3.85 fps, 8680.35 kb/s and created C:\Users\User\AppData\Local\Temp\2021-03-16@13_50_54_8110_02.264 (5.86374 MB)
3rd subjob is 2021-03-16@13_50_54_8110_03_muxing which muxes the audio and video using:
MP4Box -add "C:\Users\User\AppData\Local\Temp\2021-03-16@13_50_54_8110_02.264"#video:fps=60:name="UTC 2018-03-06 16:56:30":lang="" -brand avc1 -add "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_50_54_8110_01.aac"#audio:name="":lang="en" -mpeg4 -itags tool="Hybrid 2021.03.14.1" -tmp "C:\Users\User\AppData\Local\Temp" -new "D:\Videos\ArpieL\2021-03-16@13_50_54_8110__03.mp4"
is finished after 00:00:00.347 and created D:\Videos\ArpieL\2021-03-16@13_50_54_8110__03.mp4 (5.98646 MB).
4th subjob is 2021-03-16@13_50_54_8110_04_cleanUp which simply deletes 
"C:\Users\User\AppData\Local\Temp\2021-03-16@13_50_54_8110_02.264"
5th subjob is 2021-03-16@13_50_54_8110_05_cleanUp which simply deletes
"C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_50_54_8110_01.aac"
6th subjob is 2021-03-16@13_50_54_8110_06_rename which renames D:\Videos\ArpieL\2021-03-16@13_50_54_8110__03.mp4 to D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL_0-340.mp4
Job 2021-03-16@13_50_54_8110 finished without any errors.
No time code handling at all.

Looking at the processing of job 2021-03-16@13_51_51_0410:
1st subjob is 2021-03-16@13_51_51_0410_01_audio which extracts the audio using:[/b][/b]
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:0 -vn -sn -t 00:00:05.666 -acodec copy -map_metadata -1 -metadata encoding_tool="Hybrid 2021.03.14.1" "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_51_51_0410_01.aac"
it finished after 00:00:00.252 and created C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_51_51_0410_01.aac (0.118719 MB)
2nd subjob is 2021-03-16@13_51_51_0410_02_create which creates a time code file "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_51_51_0410.tc"
->Seems like this time codes were not ignored.
3rd subjob is 2021-03-16@13_51_51_0410_03_video which encodes the video using:
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -loglevel fatal -noautorotate -nostdin -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:1 -an -sn -t 00:00:05.666 -pix_fmt yuv420p -vsync 0 -f rawvideo - | "C:\Program Files\Hybrid\64bit\x264.exe" --preset veryslow --crf 19.50 --profile high --level 4.2 --ref 5 --keyint 600 --min-keyint 30 --b-adapt 1 --sync-lookahead 1 --rc-lookahead 40 --no-fast-pskip --subme 7 --aq-mode 2 --threads 1 --sar 1:1 --non-deterministic --demuxer raw --input-res 1920x1080 --input-csp i420 --input-depth 8 --fps 60/1 --stitchable --output-depth 8 --output "C:\Users\User\AppData\Local\Temp\2021-03-16@13_51_51_0410_03.264" -
it encoded 340 frames, 3.79 fps, 8680.35 kb/s, finished after 00:01:29.883 and created C:\Users\User\AppData\Local\Temp\2021-03-16@13_51_51_0410_03.264 (5.86374 MB)
4th subjob is 2021-03-16@13_51_51_0410_04_muxing which muxes the video into an mp4 container using:
"C:\Program Files\Hybrid\64bit\MP4Box.exe" -add "C:\Users\User\AppData\Local\Temp\2021-03-16@13_51_51_0410_03.264" -brand avc1 -tmp "C:\Users\User\AppData\Local\Temp" -new "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__04_mp4Temp.mp4"
it finished after 00:00:00.449 and created created D:\Videos\ArpieL\2021-03-16@13_51_51_0410__04_mp4Temp.mp4 (5.86784 MB)
5th subjob is 2021-03-16@13_51_51_0410_05_cleanUp, which deletes "C:\Users\User\AppData\Local\Temp\2021-03-16@13_51_51_0410_03.264"
6th subjob is 2021-03-16@13_51_51_0410_06_muxing, which tries to add the time codes to the mp4 file using:
"C:\Program Files\Hybrid\64bit\mp4fpsmod.exe" -t "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_51_51_0410.tc" -x "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__04_mp4Temp.mp4" -o "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_withTimeCodes_0.mp4"
it found 340 frames: time delta 16.6676 finished after 00:00:00.168 and created D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_withTimeCodes_0.mp4 (5.8709 MB)
7th subjob is 2021-03-16@13_51_51_0410_07_cleanUp which deletes "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__04_mp4Temp.mp4"
8th subjob is 2021-03-16@13_51_51_0410_08_cleanUp which deletes "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_51_51_0410.tc"
9th subjob is 2021-03-16@13_51_51_0410_09_rename which renames D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_withTimeCodes_0.mp4 to  D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_mp4Temp.mp4
10th subjob is 2021-03-16@13_51_51_0410_10_muxing which muxes  audio and video (with time codes) using:
"C:\Program Files\Hybrid\64bit\MP4Box.exe" -add "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_mp4Temp.mp4" -add "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_51_51_0410_01.aac"#audio:name="":lang="en" -mpeg4 -itags tool="Hybrid 2021.03.14.1" -tmp "C:\Users\User\AppData\Local\Temp" -new "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__10.mp4"
it finished after 00:00:00.429 and created D:\Videos\ArpieL\2021-03-16@13_51_51_0410__10.mp4 (5.98858 MB).
11th subjob is 2021-03-16@13_51_51_0410_11_cleanUp which deletes "D:\Videos\ArpieL\2021-03-16@13_51_51_0410__06_mp4Temp.mp4"
12th subjob is 2021-03-16@13_51_51_0410_12_cleanUp which deletes "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_51_51_0410_01.aac"
13th subjob is 2021-03-16@13_51_51_0410_13_rename which renames D:\Videos\ArpieL\2021-03-16@13_51_51_0410__10.mp4 to D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL_0-340 vfrstay.mp4
Job 2021-03-16@13_51_51_0410 finished!

<<< QUEUE FINISHED !!!! >>>> (line 207790)
Then you resetted job 2021-03-16@02_52_20_2310 and started it.

Looking at the processing of 2021-03-16@02_52_20_2310:
1st subjob is 2021-03-16@02_52_20_2310_01_audio which extracts the audio using:
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:0 -vn -sn -ss 00:00:36.866 -t 00:00:11.200 -acodec copy -map_metadata -1 -metadata encoding_tool="Hybrid 2021.03.14.1" "C:\Users\User\AppData\Local\Temp\iId_5_aid_0_lang_en_2021-03-16@02_52_20_2310_01.aac"
it finished after 00:00:05.826 and created C:\Users\User\AppData\Local\Temp\iId_5_aid_0_lang_en_2021-03-16@02_52_20_2310_01.aac (0.23377 MB)
2nd subjob is 2021-03-16@02_52_20_2310_02_create which created the time code file "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@02_52_20_2310.tc"
3rd subjob is 2021-03-16@02_52_20_2310_03_video which encodes the video using:
C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -loglevel fatal -noautorotate -nostdin -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:1 -an -sn -pix_fmt yuv420p -vsync 0 -f rawvideo - | "C:\Program Files\Hybrid\64bit\x264.exe" --preset veryslow --crf 19.50 --profile high --level 4.2 --ref 5 --keyint 600 --min-keyint 30 --b-adapt 1 --sync-lookahead 1 --rc-lookahead 40 --no-fast-pskip --subme 7 --aq-mode 2 --threads 1 --sar 1:1 --non-deterministic --demuxer raw --input-res 1920x1080 --input-csp i420 --input-depth 8 --fps 60/1 --stitchable --output-depth 8 --output "C:\Users\User\AppData\Local\Temp\2021-03-16@02_52_20_2310_03.264" -
which you stopped after 266 frames.

Then you created a new job with start: 36.8667, end: 48.0667, length: 235.866.

Looking at the processing of 2021-03-16@13_56_59_2110:
1st subjob 2021-03-16@13_56_59_2110_01_audio extracts the audio using:
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:0 -vn -sn -ss 00:00:36.866 -t 00:00:11.200 -acodec copy -map_metadata -1 -metadata encoding_tool="Hybrid 2021.03.14.1" "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_56_59_2110_01.aac"
2nd subjob is 2021-03-16@13_56_59_2110_02_create which creates a time code file C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_56_59_2110.tc
(looking at the time code file I see a potential problem, the first time code is 1 not zero)
3rd subjob is 2021-03-16@13_56_59_2110_03_video which encodes the video using:[/b]
"C:\Program Files\Hybrid\64bit\ffmpeg.exe" -y -loglevel fatal -noautorotate -nostdin -threads 4 -ignore_editlist true -i "D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL.mp4" -map 0:1 -an -sn -ss 00:00:36.866 -t 00:00:11.200 -pix_fmt yuv420p -vsync 0 -f rawvideo - | "C:\Program Files\Hybrid\64bit\x264.exe" --preset veryslow --crf 19.50 --profile high --level 4.2 --ref 5 --keyint 600 --min-keyint 30 --b-adapt 1 --sync-lookahead 1 --rc-lookahead 40 --no-fast-pskip --subme 7 --aq-mode 2 --threads 1 --sar 1:1 --non-deterministic --demuxer raw --input-res 1920x1080 --input-csp i420 --input-depth 8 --fps 60/1 --stitchable --output-depth 8 --output "C:\Users\User\AppData\Local\Temp\2021-03-16@13_56_59_2110_03.264" -
it encoded 672 frames, 3.27 fps, 10176.77 kb/s, finished after 00:03:26.069 and C:\Users\User\AppData\Local\Temp\2021-03-16@13_56_59_2110_03.264 (13.5875 MB)
4th subjob is 2021-03-16@13_56_59_2110_04_muxing which muxes the video into an mp4 container using:
"C:\Program Files\Hybrid\64bit\MP4Box.exe" -add "C:\Users\User\AppData\Local\Temp\2021-03-16@13_56_59_2110_03.264" -brand avc1 -tmp "C:\Users\User\AppData\Local\Temp" -new "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__04_mp4Temp.mp4"
it finished after 00:00:00.441 and created D:\Videos\ArpieL\2021-03-16@13_56_59_2110__04_mp4Temp.mp4 (13.5948 MB)
5th subjob is 2021-03-16@13_56_59_2110_05_cleanUp which deletes "C:\Users\User\AppData\Local\Temp\2021-03-16@13_56_59_2110_03.264"
6th subjob is 2021-03-16@13_56_59_2110_06_muxing which muxes the video and the time codes using:[/b]
"C:\Program Files\Hybrid\64bit\mp4fpsmod.exe" -t "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_56_59_2110.tc" -x "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__04_mp4Temp.mp4" -o "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_withTimeCodes_0.mp4"
672 frames: time delta 16.6667 it finished after 00:00:00.256 and created D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_withTimeCodes_0.mp4 (13.5998 MB)
(seems like the non zero starting time code didn't cause a problem)
7th subjob is 2021-03-16@13_56_59_2110_07_cleanUp which deletes "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__04_mp4Temp.mp4"
8th subjob is 2021-03-16@13_56_59_2110_08_cleanUp which deletes "C:\Users\User\AppData\Local\Temp\timecodeV2_2021-03-16@13_56_59_2110.tc"
9th subjob is 2021-03-16@13_56_59_2110_09_rename which renames D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_withTimeCodes_0.mp4 to D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_mp4Temp.mp4[/b]
10th subjob is 2021-03-16@13_56_59_2110_10_muxing which muxes the audio and the video (with time codes) using:
"C:\Program Files\Hybrid\64bit\MP4Box.exe" -add "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_mp4Temp.mp4" -add "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_56_59_2110_01.aac"#audio:name="":lang="en" -mpeg4 -itags tool="Hybrid 2021.03.14.1" -tmp "C:\Users\User\AppData\Local\Temp" -new "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__10.mp4"
it finished after 00:00:00.423 and created D:\Videos\ArpieL\2021-03-16@13_56_59_2110__10.mp4 (13.8319 MB)
11th subjob is 2021-03-16@13_56_59_2110_11_cleanUp which deletes "D:\Videos\ArpieL\2021-03-16@13_56_59_2110__06_mp4Temp.mp4"
12th subjob is 2021-03-16@13_56_59_2110_12_cleanUp which deletes "C:\Users\User\AppData\Local\Temp\iId_1_aid_0_lang_en_2021-03-16@13_56_59_2110_01.aac"
13th subjob is 2021-03-16@13_56_59_2110_13_rename which renames D:\Videos\ArpieL\2021-03-16@13_56_59_2110__10.mp4 to D:\Videos\ArpieL\2018-03-07-0052-32_ArpieL_2212-2884 vfrstay.mp4
<<< QUEUE FINISHED !!!! >>>>

So first I spend and hour reading a "HybridDebugOutput vfr to cfr, inconsistent.txt" which contains no contained no problems,....

Looking at "HybridDebugOutput fail then success.txt".

Looking at job 2021-03-16@14_15_50_2510:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh

Looking at job 2021-03-16@14_16_40_3410:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh

This is getting annoying,...

Looking at job 2021-03-16@14_25_26_7310:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh
I start to see a pattern,..

Looking at job 2021-03-16@14_26_17_4010:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh
Yes, if you press the stop button Hybrid will stop the processing the job,...


Looking at job 2021-03-16@14_26_47_7910:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh  Huh  Huh

Looking at job 2021-03-16@14_27_27_4410:
same old same old:
2021.03.16 - 14:27:50_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: x264 output: 71 frames: 3.28 fps, 10367.30 kb/s
2021.03.16 - 14:27:50_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: x264 output: 74 frames: 3.36 fps, 10014.62 kb/s
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: on_stopJobPushButton_clicked
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9:  -> calling STOP for:
  2021-03-16@14_27_27_4410
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: stopJobs called:
  2021-03-16@14_27_27_4410
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: stopping Client local for parent 2021-03-16@14_27_27_4410
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: Kill all processes of this job!
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: Killing helper,...
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: Killing process,...
2021.03.16 - 14:27:51_Windows 10 Version 1803 (64bit)_2021.03.14.1 - level 9: set 'STOPPED' for: 2021-03-16@14_27_27_4410_03_video

Looking at job 2021-03-16@14_28_16_8910:
1st job extracted the audio
2nd job wanted to encode video but was stopped by you

Now I'm getting curious, whether there will be a job procesisng which isn't stopped,...

Looking at job 2021-03-16@14_33_59_5710:
1st job extracted the audio
2nd job created a time code file
3rd job wanted to encode video but was stopped by you
Huh  Huh  Huh
not this one apperently

Looking at job 2021-03-16@14_34_34_2410:
-> this one wasn't stopped and finished!!!

<<< QUEUE FINISHED !!!! >>>>


So my conclusion is that it does not seem like a bug in Hybrid, but you or your system pressing the Stop JobPushButton (or selecting a job and using the popup menu to stop the job).

Cu Selur
Reply


Messages In This Thread
RE: Timecode Problem with Cut Support - by Selur - 16.03.2021, 19:50

Forum Jump:


Users browsing this thread: 2 Guest(s)