Bug Report Output timer stops video prematurely

M

mcao

New Member
Hi, I've been running into this issue since updating to the latest version (25.0.4). When using the output timer functionality, the recording will sometimes stop immediately, producing a 1kb file. The UI however shows that it is still recording. Normally when it is recording the file size is 0kb and will show the real size when the recording is complete. If I don't notice the 1kb file immediately, it will just keep running without throwing any errors. I for sure have the correct timer settings.

Here are the relevant log entries:
Code: 
21:59:38.572: Starting recording due to OutputTimer
21:59:38.674: [jim-nvenc: 'recording_h264'] settings:
21:59:38.674:     rate_control: CQP
21:59:38.674:     bitrate:      0
21:59:38.674:     cqp:          23
21:59:38.674:     keyint:       250
21:59:38.674:     preset:       hq
21:59:38.674:     profile:      high
21:59:38.674:     width:        1920
21:59:38.674:     height:       1080
21:59:38.674:     2-pass:       false
21:59:38.674:     b-frames:     2
21:59:38.674:     lookahead:    false
21:59:38.674:     psycho_aq:    false
21:59:38.674:
21:59:38.685: ---------------------------------
21:59:38.686: [FFmpeg aac encoder: 'Track1'] bitrate: 160, channels: 2, channel_layout: 3
21:59:38.686:
21:59:38.696: ==== Recording Start ===============================================
21:59:38.696: [ffmpeg muxer: 'adv_file_output'] Writing file 'D:/2020-04-10 21-59-38.mp4'...
21:59:39.021: [ffmpeg muxer: 'adv_file_output'] Output of file 'D:/2020-04-10 21-59-38.mp4' stopped
21:59:39.021: Output 'adv_file_output': stopping
21:59:39.021: Output 'adv_file_output': Total frames output: 1
21:59:39.021: Output 'adv_file_output': Total drawn frames: 10
21:59:39.024: ==== Recording Stop ================================================
21:59:39.030: warning: 2 frames left in the queue on closing

I've attached a sample bad video file that was generated by this bug.
 

Attachments

  • bad.zip
    252 bytes · Views: 6
M

mcao

New Member
I really hope someone can look into this because it is happening pretty frequently. I've attached an entire log file below.

But here is what I can tell. When it works, the log entries look like this...

Code: 
18:09:01.314: ==== Recording Start ===============================================
18:09:01.314: [ffmpeg muxer: 'adv_file_output'] Writing file 'D:/2020-08-03 18-09-01.mp4'...
18:47:00.301: Stopping recording due to OutputTimer timeout
18:47:00.304: Stopping recording due to OutputTimer timeout
18:47:00.306: Stopping recording due to OutputTimer timeout
18:47:00.308: Stopping recording due to OutputTimer timeout
18:47:00.309: Stopping recording due to OutputTimer timeout
18:47:00.311: Stopping recording due to OutputTimer timeout
18:47:00.313: Stopping recording due to OutputTimer timeout
18:47:00.315: Stopping recording due to OutputTimer timeout
18:47:00.317: Stopping recording due to OutputTimer timeout
18:47:00.318: Stopping recording due to OutputTimer timeout
18:47:00.320: Stopping recording due to OutputTimer timeout
18:47:00.322: Stopping recording due to OutputTimer timeout
18:47:00.324: Stopping recording due to OutputTimer timeout
18:47:00.325: Stopping recording due to OutputTimer timeout
18:47:00.327: Stopping recording due to OutputTimer timeout
18:47:00.329: Stopping recording due to OutputTimer timeout
18:47:00.329: [ffmpeg muxer: 'adv_file_output'] Output of file 'D:/2020-08-03 18-09-01.mp4' stopped
18:47:00.330: Output 'adv_file_output': stopping
18:47:00.330: Output 'adv_file_output': Total frames output: 68294
18:47:00.330: Output 'adv_file_output': Total drawn frames: 68302
18:47:00.331: Stopping recording due to OutputTimer timeout
18:47:00.333: Stopping recording due to OutputTimer timeout
18:47:00.335: Stopping recording due to OutputTimer timeout
18:47:00.337: Stopping recording due to OutputTimer timeout
18:47:00.338: Stopping recording due to OutputTimer timeout
18:47:00.345: ==== Recording Stop ================================================

When the bug happens the log looks like this...

Code: 
19:01:15.793: ==== Recording Start ===============================================
19:01:15.793: [ffmpeg muxer: 'adv_file_output'] Writing file 'D:/2020-08-03 19-01-15.mp4'...
19:01:16.074: [ffmpeg muxer: 'adv_file_output'] Output of file 'D:/2020-08-03 19-01-15.mp4' stopped
19:01:16.074: Output 'adv_file_output': stopping
19:01:16.074: Output 'adv_file_output': Total frames output: 1
19:01:16.074: Output 'adv_file_output': Total drawn frames: 9
19:01:16.074: ==== Recording Stop ================================================
 

Attachments

  • 2020-07-30 23-58-51.txt
    54 KB · Views: 6
M

mcao

New Member
This bug is still around in the latest version 27.0.1. This time I switched from mp4 to mkv and it still happens. Below is a log of when the bug occurs. Note the timestamps `11:38:40.196` and `11:38:40.606` which indicates recording stopped immediately.


Code: 
11:38:40.102: Starting recording due to OutputTimer
11:38:40.171: [jim-nvenc: 'recording_h264'] settings:
11:38:40.171:     rate_control: CQP
11:38:40.171:     bitrate:      0
11:38:40.171:     cqp:          23
11:38:40.171:     keyint:       250
11:38:40.171:     preset:       hq
11:38:40.171:     profile:      high
11:38:40.171:     width:        1920
11:38:40.171:     height:       1080
11:38:40.171:     2-pass:       false
11:38:40.171:     b-frames:     2
11:38:40.171:     lookahead:    false
11:38:40.171:     psycho_aq:    false
11:38:40.171:
11:38:40.187: ---------------------------------
11:38:40.187: [FFmpeg aac encoder: 'Track1'] bitrate: 160, channels: 2, channel_layout: 3
11:38:40.187:
11:38:40.196: ==== Recording Start ===============================================
11:38:40.196: [ffmpeg muxer: 'adv_file_output'] Writing file 'D:/2021-08-09 11-38-40.mkv'...
11:38:40.606: [ffmpeg muxer: 'adv_file_output'] Output of file 'D:/2021-08-09 11-38-40.mkv' stopped
11:38:40.606: Output 'adv_file_output': stopping
11:38:40.606: Output 'adv_file_output': Total frames output: 1
11:38:40.606: Output 'adv_file_output': Total drawn frames: 13
11:38:40.606: ==== Recording Stop ================================================
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:23:41.191: Stopping recording due to OutputTimer timeout
13:25:16.251: Starting recording due to OutputTimer
13:25:16.311: [jim-nvenc: 'recording_h264'] settings:
13:25:16.311:     rate_control: CQP
13:25:16.311:     bitrate:      0
13:25:16.311:     cqp:          23
13:25:16.311:     keyint:       250
13:25:16.311:     preset:       hq
13:25:16.311:     profile:      high
13:25:16.311:     width:        1920
13:25:16.311:     height:       1080
13:25:16.311:     2-pass:       false
13:25:16.311:     b-frames:     2
13:25:16.311:     lookahead:    false
13:25:16.311:     psycho_aq:    false
13:25:16.311:
13:25:16.327: ---------------------------------
13:25:16.327: [FFmpeg aac encoder: 'Track1'] bitrate: 160, channels: 2, channel_layout: 3
 
T

therentabrain

Member
Oh god this has just ruined a huge amount of paid work for me. I had no idea something this frustrating was still happening with no warning or notice. I may have lost a bunch of irreplaceable footage for a client. Can anything be done to fix?
 
V

volko

New Member
can confirm this is still happening and needs fixing! always 1frame.

logs are clear like in OP's example. only oddity is there "Stopping recording due to OutputTimer timeout" is always all below (after) the stopped command and all happen in the same instant, whereas in a normal case, some are above (before) stop command and some are below (after) at different time signatures.

please fix!
 
You must log in or register to reply here.
Top