[A17.1533] Colonist freezes when preparing for caravan

Started by dolphinigle, May 10, 2017, 10:31:14 AM

Previous topic - Next topic

dolphinigle

Upon preparing to create a caravan, a colonist (Billa) freezes and I received these errors.

RimWorld 0.17.1533 rev863
Verse.Log:Message(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:30)
RimWorld.VersionControl:LogVersionNumber() (at C:\Dev\RimWorld\Assets\Scripts\RimWorld\Utility\Version\VersionControl.cs:83)
Verse.Root:CheckGlobalInit() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root.cs:76)
Verse.Root:Start() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root.cs:31)
Verse.Root_Entry:Start() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Entry.cs:16)

Loading game from file Fallville (Permadeath) with mods Core
Verse.Log:Message(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:30)
Verse.SavedGameLoader:LoadGameFromSaveFile(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Map\MapIniter\SavedGameLoader.cs:17)
Verse.Root_Play:<Start>m__84A() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:29)
Verse.LongEventHandler:RunEventFromAnotherThread(Action) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\LongEventHandler.cs:439)
Verse.LongEventHandler:<UpdateCurrentAsynchronousEvent>m__847() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\LongEventHandler.cs:353)

Geyser spray sustainer still playing after 1000 ticks. Force-ending.
Verse.Log:Message(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:30)
RimWorld.Building_SteamGeyser:Tick() (at C:\Dev\RimWorld\Assets\Scripts\RimWorld\Thing\Misc\SteamGeyser.cs:61)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Billa started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Kyle started 10 jobs in 10 ticks. List: (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Billa started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Kyle started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Billa started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

... (I cut the middel part since it's too long) ...

Kyle started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice222821) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Billa started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)

Loading game from file Fallville (Permadeath) with mods Core
Verse.Log:Message(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:30)
Verse.SavedGameLoader:LoadGameFromSaveFile(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Map\MapIniter\SavedGameLoader.cs:17)
Verse.Root_Play:<Start>m__84A() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:29)
Verse.LongEventHandler:RunEventFromAnotherThread(Action) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\LongEventHandler.cs:439)
Verse.LongEventHandler:<UpdateCurrentAsynchronousEvent>m__847() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\LongEventHandler.cs:353)

Billa started 10 jobs in 10 ticks. List: (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture) , and (PrepareCaravan_GatherItems A=Thing_RawRice226307) (WaitMaintainPosture)  lastJobGiver=, curJob.def=WaitMaintainPosture, curDriver=Verse.AI.JobDriver_WaitMaintainPosture
Verse.Log:Error(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\Utility\Debug\Log\Log.cs:48)
Verse.AI.Pawn_JobTracker:StartErrorRecoverJob(String) (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:501)
Verse.AI.Pawn_JobTracker:FinalizeTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:161)
Verse.AI.Pawn_JobTracker:JobTrackerTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\AI\Pawn_JobTracker.cs:109)
Verse.Pawn:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Pawn\Pawn.cs:485)
Verse.TickList:Tick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickList.cs:126)
Verse.TickManager:DoSingleTick() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:276)
Verse.TickManager:TickManagerUpdate() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Ticking\TickManager.cs:243)
Verse.Game:UpdatePlay() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Game\Game.cs:481)
Verse.Root_Play:Update() (at C:\Dev\RimWorld\Assets\Scripts\Verse\Global\Root\Root_Play.cs:73)


[attachment deleted by admin due to age]

GiantSpaceHamster

NOTE: Tested with A17.1536

I also have this problem when preparing a caravan. With the attached save game, do the following immediately when the game loads:
- World View
- Form Caravan
   - Tony
   - Insolitus
   - Three alpacas
   - All smokeleaf (six rows)
   - Leave to the south (probably doesn't matter)

At some point while packing the caravan one or more pawns get into an ongoing loop of "... started 10 jobs in 10 ticks". Usually it's two pawns, but I've seen four get stuck for brief periods of time. The caravan does not seem to finish being loaded as one or two always seem to get stuck in this loop. I tried reloading 5 times to see if the outcome is different. Exactly who gets stuck when seems to change, but the problem always occurs.

If you do the same loadout but without the three alpacas, the error happens but seems to get "unstuck" very quickly and the caravan gets loaded and leaves successfully. So loading pack animals is not a prerequisite for the bug, but it does appear to exaggerate the problem.

Save game just prior to the steps above and output log after performing those steps are attached.

Unrelated, at the end of the log are some entries indicating "HandleD3DDeviceLost" that I believe are related to the reports of music and sound cutting in and out. Note that the game is running in windowed mode with "play in background" enabled, and the sound issue appears to happen when the game is in the background minimized but I have not been testing that, just happened to happen while writing this and I noticed the entries in the log.

[attachment deleted by admin due to age]

ison

Zorba fixed this. Thanks for reporting.

QuoteUnrelated, at the end of the log are some entries indicating "HandleD3DDeviceLost" that I believe are related to the reports of music and sound cutting in and out. Note that the game is running in windowed mode with "play in background" enabled, and the sound issue appears to happen when the game is in the background minimized but I have not been testing that, just happened to happen while writing this and I noticed the entries in the log.

Interesting, thanks.