View Issue Details

IDProjectCategoryLast Update
0026794AI War 2Crash/ExceptionApr 4, 2022 5:07 pm
ReporterBadgerBadger Assigned ToChris_McElligottPark  
Status resolvedResolutionfixed 
Product Version4.008 Exogalactic Wayfinding 
Fixed in Version4.011 Multiplayer Stability 
Summary0026794: MP client errors
Description
3/26/2022 7:48:29 PM 4.008 CLIENT DELAYED2067 TID36 Error! Called TypeData 323, which was already back in the pool!
  at System.Environment.get_StackTrace () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at Arcen.Universal.ArcenDebugging.ArcenDebugLog (System.String Message, Arcen.Universal.Verbosity Verbosity) [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at Arcen.AIW2.Core.GameCommand.get_TypeData () [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.Core.GameCommand.Execute (Arcen.AIW2.Core.ArcenClientOrHostSimContextCore Context) [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.Core.World_AIW2.OnClientOrHost_ExecuteGameCommandsThatWereScheduled (Arcen.AIW2.Core.ArcenClientOrHostSimContextCore Context) [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.External.SimPlannerImplementation.ProcessCoreLogicForArbitraryFrameOnMainThread (System.Boolean& stalledWaitingOnConnections, System.Boolean& mayUpdateVisuals, System.String& reasonForNoVisualUpdates) [0x00000] in <5b73b996993140af92461924aa79cd9e>:0
  at Arcen.AIW2.Core.Engine_AIW2.<ProcessArbitraryFrameOnMainThread>b__75_0 () [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.Universal.ArcenThreading+<>c__DisplayClass11_0.<RunTaskOnBackgroundThread>b__0 () [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at System.Threading.Tasks.Task.InnerInvoke () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.Execute () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecutionContextCallback (System.Object obj) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.RunInternal (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.Run (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal (System.Threading.Tasks.Task& currentTaskSlot) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteEntry (System.Boolean bPreventDoubleExecution) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.System.Threading.IThreadPoolWorkItem.ExecuteWorkItem () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ThreadPoolWorkQueue.Dispatch () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading._ThreadPoolWaitCallback.PerformWaitCallback () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0

3/26/2022 7:48:29 PM 4.008 CLIENT DELAYED2068 TID36 Null TypeData for GameCommand -- did you (developer/modder) create the proper xml entry in the GameData\Configuration\GameCommand folder?
  at System.Environment.get_StackTrace () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at Arcen.Universal.ArcenDebugging.ArcenDebugLog (System.String Message, Arcen.Universal.Verbosity Verbosity) [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at Arcen.AIW2.Core.GameCommand.Execute (Arcen.AIW2.Core.ArcenClientOrHostSimContextCore Context) [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.Core.World_AIW2.OnClientOrHost_ExecuteGameCommandsThatWereScheduled (Arcen.AIW2.Core.ArcenClientOrHostSimContextCore Context) [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.External.SimPlannerImplementation.ProcessCoreLogicForArbitraryFrameOnMainThread (System.Boolean& stalledWaitingOnConnections, System.Boolean& mayUpdateVisuals, System.String& reasonForNoVisualUpdates) [0x00000] in <5b73b996993140af92461924aa79cd9e>:0
  at Arcen.AIW2.Core.Engine_AIW2.<ProcessArbitraryFrameOnMainThread>b__75_0 () [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.Universal.ArcenThreading+<>c__DisplayClass11_0.<RunTaskOnBackgroundThread>b__0 () [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at System.Threading.Tasks.Task.InnerInvoke () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.Execute () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecutionContextCallback (System.Object obj) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.RunInternal (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.Run (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal (System.Threading.Tasks.Task& currentTaskSlot) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteEntry (System.Boolean bPreventDoubleExecution) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.System.Threading.IThreadPoolWorkItem.ExecuteWorkItem () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ThreadPoolWorkQueue.Dispatch () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading._ThreadPoolWaitCallback.PerformWaitCallback () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0

3/26/2022 7:48:29 PM 4.008 CLIENT DELAYED2069 TID29 Error! Called FromActualInputEventOfPlayerID on game command 331, which was already back in the pool!
  at System.Environment.get_StackTrace () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at Arcen.Universal.ArcenDebugging.ArcenDebugLog (System.String Message, Arcen.Universal.Verbosity Verbosity) [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at Arcen.AIW2.Core.GameCommand.get_FromActualInputEventOfPlayerID () [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.Core.World_AIW2.OnClientOrHost_ExecuteGameCommandsThatWereScheduled (Arcen.AIW2.Core.ArcenClientOrHostSimContextCore Context) [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.AIW2.External.SimPlannerImplementation.ProcessCoreLogicForArbitraryFrameOnMainThread (System.Boolean& stalledWaitingOnConnections, System.Boolean& mayUpdateVisuals, System.String& reasonForNoVisualUpdates) [0x00000] in <5b73b996993140af92461924aa79cd9e>:0
  at Arcen.AIW2.Core.Engine_AIW2.<ProcessArbitraryFrameOnMainThread>b__75_0 () [0x00000] in <3bc68911c82746c1abea6c292dacb293>:0
  at Arcen.Universal.ArcenThreading+<>c__DisplayClass11_0.<RunTaskOnBackgroundThread>b__0 () [0x00000] in <16b64163e0654b91aaa9a5e637546c85>:0
  at System.Threading.Tasks.Task.InnerInvoke () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.Execute () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecutionContextCallback (System.Object obj) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.RunInternal (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ExecutionContext.Run (System.Threading.ExecutionContext executionContext, System.Threading.ContextCallback callback, System.Object state, System.Boolean preserveSyncCtx) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal (System.Threading.Tasks.Task& currentTaskSlot) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.ExecuteEntry (System.Boolean bPreventDoubleExecution) [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.Tasks.Task.System.Threading.IThreadPoolWorkItem.ExecuteWorkItem () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading.ThreadPoolWorkQueue.Dispatch () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
  at System.Threading._ThreadPoolWaitCallback.PerformWaitCallback () [0x00000] in <695d1cc93cca45069c528c15c9fdd749>:0
TagsNo tags attached.

Activities

Chris_McElligottPark

Apr 4, 2022 5:07 pm

administrator   ~0065737

Thanks!

* At last, seem to have fixed a general multiplayer error that was initially having clients get "corrupted seeming" game commands, but really was a matter of those having been partly put back in the pool already. Essentially this was a client-only threading issue, and related to how we used a pair of rotating queues.
** Instead of using a pair of rotating queues, it now uses a single queue, and then a feeder working list, and this dodges the timing issues entirely (and is just as efficient).
** This particular bug was what was causing the flood of errors on some MP clients some of the time recently (the flood of errors was actually good, because it meant that we were finding out about the issue rather than executing malformed gamecommands).

Issue History

Date Modified Username Field Change
Mar 26, 2022 11:16 pm BadgerBadger New Issue
Mar 26, 2022 11:16 pm BadgerBadger Status new => assigned
Mar 26, 2022 11:16 pm BadgerBadger Assigned To => Chris_McElligottPark
Apr 4, 2022 5:07 pm Chris_McElligottPark Status assigned => resolved
Apr 4, 2022 5:07 pm Chris_McElligottPark Resolution open => fixed
Apr 4, 2022 5:07 pm Chris_McElligottPark Fixed in Version => 4.011 Multiplayer Stability
Apr 4, 2022 5:07 pm Chris_McElligottPark Note Added: 0065737