View Issue Details

IDProjectCategoryView StatusLast Update
0000084madVRbugpublic2013-07-20 14:34
Reportertruexfan81 Assigned Tomadshi  
PrioritynormalSeverityminorReproducibilityalways
Status closedResolutionfixed 
Platformx64OSWin7 
Summary0000084: occasional black frames with smooth motion
Descriptionthis is a known bug, i believe it was supposed to be fixed in 0.86.2, but with that and 0.86.3 i still have it with 59.94fps video, probably others also, just haven't noticed yet
Steps To Reproducewatch a 59.94fps video with smooth motion active and look for the occasional black frame
TagsNo tags attached.
madVR Version0.86.3
Media Player (with version info)mpc-hc 1.6.8.7407 (3f01902)
Splitter (with version info)LAV 0.57
Decoder (with version info)LAV 0.57
DecodingCUDA
Deinterlacingnone (progressive)
DXVA2 Scaling Activeno
Aero / Desktop CompositionOn
Problem occurs with modeall modes
GPU ManufacturerNVidia
GPU Modelgtx650
GPU Driver Version320.18

Activities

madshi

2013-06-11 10:10

administrator   ~0000181

I'll need a log file. When you try to reproduce the issue, you should always delete the log file before starting a new movie playback. And it would be very cool if you could close the media player as fast as possible after the black frame appeared. It would also be nice if you could maybe count the seconds it took from the black frame to closing the media player, to help me find the right place to look for in the log. You don't have to upload the whole log. If you look at the first column of the log, it contains the time difference from the start of media playback until each logged event. So e.g. if you needed 10 seconds from black frame to closing the media player, then just make sure the last e.g. 30 seconds are still in the log file. You can delete the rest. But you can also upload the whole log file, if you prefer it that way. Just make sure you zip it.

truexfan81

2013-06-11 19:27

reporter   ~0000197

will test after work tonight, i think my pc needs a reboot, this morning its not letting me use smooth motion in windowed mode either, with this vid (xvid 25fps), if i activate smooth motion right now, gpu usage goes to 30% and starts massive frame dropping

truexfan81

2013-06-11 19:57

reporter   ~0000200

hmm this is interesting, when i activate smooth motion this morning everything is full except the backbuffer queue, it stays empty, the rest stay full, any idea what would cause that?

madshi

2013-06-11 20:01

administrator   ~0000201

This bug report is about "occasional black frames with smooth motion". Please let's not turn this bug report into a general smooth motion support topic. The next post to this bug report should be a log file upload.

truexfan81

2013-06-12 06:49

reporter   ~0000203

madshi i can't find log files for mpc-hc or madvr, i don't suppose you know where they are stored in win7? i even looked in %appdata%

thanks

cyberbeing

2013-06-12 07:23

reporter   ~0000204

When you activate madVR debug mode, it should write a log to the Desktop.

madshi

2013-06-12 07:54

administrator   ~0000205

Yep, and please check the first comment here, for detailed instructions how to handle the log file.

truexfan81

2013-06-15 00:49

reporter   ~0000207

ok been playing with this for a few days, it seems it won't reproduce the problem while in debug mode, i think i saw someone on the forums say he noticed that too.

madshi

2013-06-15 21:07

administrator   ~0000208

Oh well. Can you please try displaying the madVR OSD (Ctrl+J) and when those black frames occur, do you notice anything interesting in the queues? E.g. are some queues near empty, or emptier than usual? Or is there a frame drop/repeat or a presentation glitch when the black frame occurs? Anything like that?

Ryrynz

2013-06-17 01:45

reporter   ~0000212

I haven't seen any glitches or dropped frames when it happens. It looks like we'll need to have the OSD open during playback to check the queues straight away.

truexfan81

2013-06-17 05:27

reporter   ~0000213

ok it appears to have fixed itself when i changed my flush settings, so lets go ahead and close this one, if i see another black frame i'll reopen it

Ryrynz

2013-06-17 05:33

reporter   ~0000214

So is it fixed if the defaults are used? Flush after immediate render steps and flush and wait (sleep) after last render step? Because I've just set those under exclusive mode to fix an issue with the mouse cursor appearing just before playback of the next file, so we'll see if that actually fixes it.

madshi

2013-06-17 08:03

administrator   ~0000215

That would be interesting. What did you have your flush settings set to?

Ryrynz

2013-06-17 08:17

reporter   ~0000216

I had mine all set to don't flush.. for whatever minor performance improvement I got from it. I was thinking if these sorts of issues are side effects of changing the defaults (black frame, mouse cursor appearing etc) perhaps they are important to the way MadVR is designed and shouldn't be changed? I think you were intending to look at this close to 1.0.

madshi

2013-06-17 08:42

administrator   ~0000217

In theory madVR should force a flush where absolutely necessary. At least this should be done for the "use a separate device for presentation" and for smooth motion FRC. So if you can reproduce the black frame only with specific flush settings, it would be nice to hear which setting exactly makes the difference.

I understand that this is tiresome to test, but it would help madVR development. So if you could figure that out, that would be nice!

truexfan81

2013-06-17 08:52

reporter   ~0000218

Last edited: 2013-06-17 08:54

i saw a black frame a few minutes ago, this was with smooth motion and deinterlace on, i immediately went into debug mode, all of the queues were either full or close to full

edit: it still will not do it while in debug mode, so i can't get anything logged for it

madshi

2013-06-17 08:57

administrator   ~0000219

> i saw a black frame a few minutes ago

With the default flush settings? Or with custom flush settings?

As I said before: Please activate the debug OSD all the time, so you can look at the queue state right away without first having to activate the OSD.

Ryrynz

2013-06-17 14:26

reporter   ~0000220

I set default flush settings and I got the black frame tonight. I'll enable full flushing and see if that changes anything but I suspect it is not flushing related.

madshi

2013-06-17 14:34

administrator   ~0000221

If the default flush settings don't help, then don't bother. Instead please turn the debug OSD on and when that black frame appears, check if any of the queues are near empty, or if anything else looks suspicious.

Ryrynz

2013-06-18 06:11

reporter   ~0000222

Okay, I'm usually watching content not testing it, I wish there was a way to log what the OSD only displays without the OSD visable, without it having to switch to debug mode.

truexfan81

2013-06-18 06:50

reporter   ~0000223

from forums:(bugmen0t) @madshi
I was able to see several black frames with OSD on however there wasn't anything suspicious. Every queue looks fine before and after it happens, no changes at all.

you asked him to post it here, he never did, so i'm doing it for him :) good day

omarank

2013-06-18 07:56

reporter   ~0000224

I also encountered black frames when playing an interlaced movie file using madVR's IVTC and smooth motion on (the file had bad timestamps though, as you observed). Black frames were seen with the default flush settings and with all flush options set to don't flush. When I changed the flush settings as: don't flush, flush & wait (sleep), don't flush and don't flush; the black frames disappeared. When black frames are seen, all the queues look fine and nothing looks suspicious in the OSD. There is one more observation: if I pause the video and then play it again, black frames stop appearing.

madshi

2013-06-18 08:12

administrator   ~0000225

Well, this all sounds pretty bad for me: Nothing suspicious in the OSD means I've no hint at all where to look for the bug... ;-(

Ryrynz

2013-06-18 08:39

reporter   ~0000226

Against your advice Madshi I'll try don't flush, flush & wait (sleep), don't flush and don't flush as Omarank mentioned that worked for him and I'll report back.

truexfan81

2013-06-18 08:51

reporter   ~0000227

Last edited: 2013-06-18 08:51

at this point i'm running don't flush for every option in windowed mode, if i try don't flush, flush & wait (sleep), don't flush and don't flush

it starts fluctuating between 9 and 19ms rendering time, with straight don't flush i'm showing 0.29ms

Ryrynz

2013-06-18 09:19

reporter   ~0000228

At this point I think we're aiming for what fixes it and what doesn't, once we've got that nailed down (if at all possible) then Madshi can hopefully fix it and you can have your preferred settings without the issue so it's probably irrelevant to mentioning rendering time, we only care about the black frame. Try running with those settings and see if they fix it.

truexfan81

2013-06-18 09:34

reporter   ~0000229

trying don't flush, flush & wait (sleep), don't flush and don't flush i just got a black frame, all queues were perfect, nothing looked suspicious

Ryrynz

2013-06-18 09:50

reporter   ~0000230

Madshi, as it seems the debug build is not affected by this problem is there anything from the debug mode that can be ported across to a test build?

madshi

2013-06-18 09:55

administrator   ~0000231

No.

Ryrynz

2013-06-18 10:04

reporter   ~0000232

Does selecting different refresh rates change anything? Short of that I'm unsure of anything else we can use to narrow it down.

truexfan81

2013-06-18 10:06

reporter   ~0000233

switching it by how much? my monitor can do 50,59, and 60Hz. i have the problem with both 59 and 60, i have not tried 50

Ryrynz

2013-06-18 10:08

reporter   ~0000234

Might as well, otherwise this could sit on the back burner for awhile and I'm sure we'd all rather it didn't.

bugmenot

2013-06-18 10:20

reporter   ~0000235

Last edited: 2013-06-18 10:25

@truexfan81
Thanks for reposting my answer from the forum here.

What I was always wondering about is what part in madVR is able to construct a black frame. Flushing and only that?
Since it only happens occasionally it's probably timing related, maybe a race condition or something. That would also explain why the debug mode isn't affected.
Though I guess that madshi even thought about this from the beginning, it would mean that users are very unlikely able to give any useful help. :(
Still if we can, just let us know how.

truexfan81

2013-06-18 10:22

reporter   ~0000236

ok now at 50hz, will see what happens

madshi

2013-06-18 10:44

administrator   ~0000237

The only 3 things that help would be if...

(1) ... you found a settings combination where the problem does not occur.
(2) ... you found something suspicious in the OSD when the black frame appears.
(3) ... you could create a debug log which captures the problem.

If you can't provide none of this, it's going to be hard for me to fix. Of course I'll still try...

Ryrynz

2013-06-18 14:51

reporter   ~0000238

Had my flush settings set to flush, flush and wait (sleep) flush, flush and had the black frame occur 3 times over a hour, that's two more than I would have had if I had all set to don't flush, so these options are playing a part in how often this occurs.

bugmenot

2013-06-18 17:11

reporter   ~0000239

I am trying to reproduce black frames with the old path FSE. No luck so far but it's too early to draw any conclusions from that.

madshi

2013-06-18 17:28

administrator   ~0000240

I thought it occurred in windowed mode, too? If so, testing the old FSE path won't really help much, even if the issue doesn't occur there.

DragonQ

2013-07-19 18:19

reporter   ~0000293

I definitely happens in windowed mode since I see it with 25/50 fps content and I don't use the exclusive or overlay mode modes.

madshi

2013-07-20 14:34

administrator   ~0000296

Thanks for a very helpful log from DragonQ, this bug should be fixed in the next build. :-)

Issue History

Date Modified Username Field Change
2013-06-09 21:53 truexfan81 New Issue
2013-06-11 10:10 madshi Note Added: 0000181
2013-06-11 10:10 madshi Assigned To => madshi
2013-06-11 10:10 madshi Status new => feedback
2013-06-11 19:27 truexfan81 Note Added: 0000197
2013-06-11 19:27 truexfan81 Status feedback => assigned
2013-06-11 19:35 madshi Status assigned => feedback
2013-06-11 19:57 truexfan81 Note Added: 0000200
2013-06-11 19:57 truexfan81 Status feedback => assigned
2013-06-11 20:01 madshi Note Added: 0000201
2013-06-11 20:02 madshi Status assigned => feedback
2013-06-12 06:49 truexfan81 Note Added: 0000203
2013-06-12 06:49 truexfan81 Status feedback => assigned
2013-06-12 07:23 cyberbeing Note Added: 0000204
2013-06-12 07:54 madshi Note Added: 0000205
2013-06-12 07:54 madshi Status assigned => feedback
2013-06-15 00:49 truexfan81 Note Added: 0000207
2013-06-15 00:49 truexfan81 Status feedback => assigned
2013-06-15 21:07 madshi Note Added: 0000208
2013-06-15 21:07 madshi Status assigned => feedback
2013-06-17 01:45 Ryrynz Note Added: 0000212
2013-06-17 05:27 truexfan81 Note Added: 0000213
2013-06-17 05:27 truexfan81 Status feedback => assigned
2013-06-17 05:33 Ryrynz Note Added: 0000214
2013-06-17 08:03 madshi Note Added: 0000215
2013-06-17 08:03 madshi Status assigned => feedback
2013-06-17 08:17 Ryrynz Note Added: 0000216
2013-06-17 08:42 madshi Note Added: 0000217
2013-06-17 08:52 truexfan81 Note Added: 0000218
2013-06-17 08:52 truexfan81 Status feedback => assigned
2013-06-17 08:54 truexfan81 Note Edited: 0000218
2013-06-17 08:57 madshi Note Added: 0000219
2013-06-17 08:57 madshi Status assigned => feedback
2013-06-17 14:26 Ryrynz Note Added: 0000220
2013-06-17 14:34 madshi Note Added: 0000221
2013-06-18 06:11 Ryrynz Note Added: 0000222
2013-06-18 06:50 truexfan81 Note Added: 0000223
2013-06-18 06:50 truexfan81 Status feedback => assigned
2013-06-18 07:56 omarank Note Added: 0000224
2013-06-18 08:12 madshi Note Added: 0000225
2013-06-18 08:39 Ryrynz Note Added: 0000226
2013-06-18 08:51 truexfan81 Note Added: 0000227
2013-06-18 08:51 truexfan81 Note Edited: 0000227
2013-06-18 09:19 Ryrynz Note Added: 0000228
2013-06-18 09:34 truexfan81 Note Added: 0000229
2013-06-18 09:50 Ryrynz Note Added: 0000230
2013-06-18 09:55 madshi Note Added: 0000231
2013-06-18 10:04 Ryrynz Note Added: 0000232
2013-06-18 10:06 truexfan81 Note Added: 0000233
2013-06-18 10:08 Ryrynz Note Added: 0000234
2013-06-18 10:20 bugmenot Note Added: 0000235
2013-06-18 10:22 truexfan81 Note Added: 0000236
2013-06-18 10:25 bugmenot Note Edited: 0000235
2013-06-18 10:44 madshi Note Added: 0000237
2013-06-18 14:51 Ryrynz Note Added: 0000238
2013-06-18 17:11 bugmenot Note Added: 0000239
2013-06-18 17:28 madshi Note Added: 0000240
2013-07-19 18:19 DragonQ Note Added: 0000293
2013-07-20 14:34 madshi Note Added: 0000296
2013-07-20 14:34 madshi Status assigned => closed
2013-07-20 14:34 madshi Resolution open => fixed