Intermittent Stack Overflow When Coming Out of TimeWarp

[White Owl]

I know this isn't following all the instructions for getting help... I'm starting to troubleshoot the issue and thought I'd make a shot in the dark, see if anybody has had a similar issue. Then I'll go digging for all the minutiae.

Sometimes, but not always, when slowing down from fast forward to normal time the game will slow down drastically, about one frame per second or slower. KSP.log says:

[LOG 21:25:59.329] Unpacking ScanSat 03
[EXC 21:26:01.904] StackOverflowException: The requested operation caused a stack overflow.

Output_log says:

Unpacking ScanSat 03

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

FAR Raycast mask: 557059 557063

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

ArgumentException: Getting control 1's position in a group with only 1 controls when doing Repaint
Aborting
  at UnityEngine.GUILayoutGroup.GetNext () [0x00000] in <filename unknown>:0 

  at UnityEngine.GUILayoutUtility.BeginLayoutArea (UnityEngine.GUIStyle style, System.Type LayoutType) [0x00000] in <filename unknown>:0 

  at UnityEngine.GUILayout.BeginArea (Rect screenRect, UnityEngine.GUIContent content, UnityEngine.GUIStyle style) [0x00000] in <filename unknown>:0 

  at UnityEngine.GUILayout.BeginArea (Rect screenRect) [0x00000] in <filename unknown>:0 

  at Toolbar.Button.drawToolTip () [0x00000] in <filename unknown>:0 

  at Toolbar.Toolbar.drawButtonToolTips () [0x00000] in <filename unknown>:0 

  at Toolbar.Toolbar.draw () [0x00000] in <filename unknown>:0 

  at Toolbar.ToolbarManager.OnGUI () [0x00000] in <filename unknown>:0 

(Filename:  Line: -1)

recalculating orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223029558.0029, -56667980.3585735, -54509979602.2265]   rVel: [19516.5601421907, 24499.2661381812, -65.5976918260381] |31322.7467529645|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419921269.692843, 65858781.5462281, -56667978.9472029]   rVel: [-73.3461619875561, -65.5976918260381, 161.45121357057] |189.074617584219|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculating orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419921194.879359, -56668045.8567928, 65858946.2263993]   rVel: [-73.3469636393763, 161.451087842516, -65.5975836438068] |189.074783671842|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223050251.1137, -54509953637.1113, -56668047.459205]   rVel: [-73.3469636393747, -65.5975836438068, 161.451087842517] |31322.7462076782|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculating orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223497958.4983, -56669552.264688, -54509391621.9815]   rVel: [19516.3513672984, 24499.4322290537, -65.5974176457009] |31322.7465791084|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419920316.43946, 65863628.9672173, -56669552.4665993]   rVel: [-73.3458067959255, -65.5974176457009, 161.44897369] |189.072472036211|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculating orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419920241.626339, -56669619.3759086, 65863793.6451017]   rVel: [-73.3466084458125, 161.448847952709, -65.5973094604809] |189.072638116186|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223518669.2095, -54509365648.5738, -56669618.8309659]   rVel: [-73.3466084458123, -65.5973094604809, 161.44884795271] |31322.746036421|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculating orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223976129.8654, -56671156.429238, -54508791379.8267]   rVel: [19516.1378381775, 24499.6021178413, -65.5971398432924] |31322.7464159201|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419919312.118189, 65868563.3663056, -56671158.3402681]   rVel: [-73.3456648535539, -65.5971398432924, 161.446879706404] |189.070532541071|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculating orbit for Ast. FKE-300 (unloaded): Kerbin
rPos: [419919237.305209, -56671225.2492974, 65868728.0420624]   rVel: [-73.3464665016213, 161.446753959678, -65.5970316549935] |189.070698613869|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

recalculated orbit for Ast. FKE-300 (unloaded): the Sun
rPos: [68223996829.9961, -54508765405.0122, -56671226.1287764]   rVel: [-73.3464665016218, -65.5970316549935, 161.446753959677] |31322.7458717649|

(Filename: C:/BuildAgent/work/d63dfc6385190b60/artifacts/StandalonePlayerGenerated/UnityEngineDebug.cpp Line: 49)

HandleD3DDeviceLost
  HandleD3DDeviceLost: still lost
Skipped rendering frame because GfxDevice is in invalid state (device lost)

(Filename:  Line: 1502)

HandleD3DDeviceLost
  HandleD3DDeviceLost: still lost
Skipped frame because GfxDevice is in invalid state (device lost)

(Filename:  Line: 1617)

Then I slowly manage to go to Space Center, and the problem disappears. I can look at the tracking station and select the flight like normal, and it will load back up like normal.

What gives? I see the single line about FAR raycast, and something about control 1 when doing repaint, and something about the Toolbar... I don't know what any of that means, or how it's relevant. Is that talking about the stock toolbar or Blizzy's? This is a heavily modded install. I'm certain I have the most current release of FAR. Obviously I need to check all the others for currency too... but was just hoping first, shot in the dark, that somebody else knows what I should be looking for.

[Padishar]

I'm a bit surprised that FAR is doing anything with raycasts while you are outside the atmosphere (I assume you are outside the atmosphere). This was the normal, non-physics timewarp, wasn't it (>= 5x)?

The argument exception and call stack is related to Blizzy's toolbar. I have seen a very similar message in other logs at least a couple of times but don't know what causes it. As it appears to be something related to tooltips, did you, by any chance, have the mouse over the toolbar when you came out of warp?

Can you upload the whole of that output_log.txt somewhere?

[White Owl]

Yes, out of atmosphere. Yes, non-physics warp. No idea if I was mousing over something, but I'll definitely try to pay attention to that possibility for further troubleshooting.

I uhh... sorta... lost the output_log. 'Cause I'm a derp.

When the problem recurs next time, I'll definitely grab it.

[jkadarn]

I had the same bug and managed to reproduced it with only FAR (0.14.3.2) installed.

See: http://forum.kerbalspaceprogram.com/threads/20451-0-25-Ferram-Aerospace-Research-v0-14-3-2-10-21-14?p=1547265&viewfull=1#post1547265

[ferram4]

FAR isn't running any raycasts outside the atmosphere; those are simply the layers the colliders are on so it doesn't waste time checking other layers.

As I have said numerous times before, this is obviously a stock issue; none of the methods in the stack trace exist in FAR, only in the stock game.