Unaccounted frame time in profiler

Artfunkel

Can anyone explain this?



The profiled frame took 32ms (31fps) to generate. But the various threads that generated it only add up to about 22ms (45fps). Where did the extra time come from? The profiler's top panel is buggy in this build, is the main display incorrect too?

DC_Darkling

the odd thing is.. my profiler works fine.

I have been wondering.. can you lot with the weird profilers test the following:
* to go your windows start menu and start command prompt.
- Alternatively go to start menu, then run option, there type cmd and you also got the prompt.
* type "ping 127.0.0.1 /t" in the command prompt and hit enter. (without the "")
* After it went on for lets say 5 or 6 goes, check if there are any minus values or values in the thousands in this screen.
* close and let me know.

* alternatively completely unsubscribe from any mod & delete the mods from your workshop folder.

Artfunkel

I think I found the problem:



ClientGame::Update incorrectly reports no time spent.

Trouble is, the sum of the bigger bars is 39.5...but the frame only took 35.4 to render! Perhaps there are some asynchronous threads being counted strange ways?

Edit: nothing unusual happened when I ran ping.

ezekel

what do the numbers mean, mason?

DC_Darkling

I have noticed form every picture of every person having the weird incorrect profiler % values, that the game one is freaking huge on ms.
Something odd is going on here.

ScardyBob

DC_Darkling said:

I have noticed form every picture of every person having the weird incorrect profiler % values, that the game one is freaking huge on ms.
Something odd is going on here.

I've actually run into this once but haven't been any to repro it since. I also couldn't tell if it was an issue with the game or the profiler itself.

DC_Darkling

Thats my point.
The only ones lately who complain are people who ALSO have weird profile pictures.
I tried to reproduce this myself so many times, but it just wont do.

So I assume its related.

Artfunkel

The % bars work correctly at the main menu, and ClientGame::Update correctly reports the time it spent. But the moment that I finish loading a map (on a listenserver with no mods) everything starts behaving as per the screenshots above.

This is spammed over and over into the console whenever the profiler is open during a game:

<pre>Error: ExitNode for ClientGame::UpdateScript doesn't have matching EnterNode (current node is GUIDeathScreen:Update)
Error: ExitNode for ClientGame::Update doesn't have matching EnterNode (current node is GUIDeathScreen:Update)
Error: ExitNode for GUIManager:Update doesn't have matching EnterNode (current node is GUIDeathScreen:Update)</pre>

None of those functions are profiled correctly.

ScardyBob

So I've had this happen to me a few times now and its appears to occur and fix itself at random. The only way I know its happening is that my fps randomly starts to tank. Besides the console spam from turning on profiler that Artfunkel has described, there doesn't appear to be any other errors. Even more bizarre, after trying to turn on/off various other debug commands, the problem disappears. 

DC_Darkling

I will add to the bizarre. I managed to get the same weird profiler without tanking fps.

I have only see people with problems with this weird profiler, so I asume im the odd one out.

baconbits

Hey my profiler is weird as hell and I'm getting the same error message with high ms. People just think its my processor giving me shitty frames though >.< .

@artfunkel you seem to have a strong understanding of this stuff and I've already seen you point out a bug in another topic. You should check out my post about ns2 and skyrim performance. I have a lot of data there (on the same issue?) that can be analyzed.