Severe and reproducible, settlement related, CPU capped, performance issue in U9

Morbad · Dec 10, 2021

Take a gander:

Source: https://www.youtube.com/watch?v=UyXGqJyqXoU

Game gets stuck at an apparently CPU limited (as prior tests of mine have shown) 91FPS on my setup, until some event (see 3:05 and 16:40), possibly object/NPC related, allows it to become 'unstuck'...after which the game behaves as expected.

On my first run of U9 I dismissed this as some sort of loading issue, but quickly came to realize that it would persist indefinitely until the unsticking action was triggered, whatever that actually is. Afterwards, I was running at a higher resolution which kept the frame rate below 91 much of the time, so it was only when trying lower resolutions that the problem became blatantly obvious. Other settlements also appear to have this issue, though the Tutorial makes for an easy demonstration.

In hindsight, this issue has been around since at least U7, but was burried under so many other performance problems that I was not able to isolate it. This has likely been behind reports of poor performance until settlements were partially cleared.

I strongly expect other setups, with slower single-threaded performance than this 5800X, to potentially be stuck at significantly lower frame rates, which is probably the cause of many of the erratic performance reports for U9.

Frop · Dec 10, 2021

On my i5-7500, the game performs more or less the same as with Update 8. As for settlements, the only times I get 60 FPS or more are very isolated cases of small, abandoned settlements and even then it's very inconsistent (for instance getting 60+ while in the middle of the settlement and getting 30 FPS at the landing pad when there's actually not that much to look at). The absence of "live" NPCs has always had a noticeable impact.

But it still confuses me why I also get worse performance in "Horizons" type situations. Examples would be ship CZs (from 120 stable to 90ish at best), fleet carriers/outposts (idem) and neutron stars (better than at launch but still fluctuating between 70-100 FPS in a worryingly steady pulse). Even in a completely empty system where I still get 120 FPS stable, the CPU and GPU utilisation are much higher than in Horizons.

Roadvsmap · Dec 10, 2021

I added this ticket today : https://issues.frontierstore.net/issue-detail/45868

Because I am getting absolutely bad FPS drops in empty / surface situations now. U8 was great for me, really smooth 60FPS on medium settings, but now it has tanked quite drastically. Dropping settings doesn't really do a lot, pointing to some other issue at hand here. Maybe it is related to the CTDs of some users flying in low altitudes.

Weirdly, inside stations everything has been fine for the hour a played in those. Smooth FPS on a planet base, in Coriolis stations and so on. So why would an "empty" surface tank performance so much?

Pigletslastand · Dec 10, 2021

Am also finding settlements worse in U9 for some reason..weird coz station concourses are a tad better for me.

quackinthebush · Dec 11, 2021

Fdev, give this guy a job already! Or at least hire him as an independent contractor. Morbad you should be getting paid for all the effort you put into troubleshooting this game.

Morbad · Dec 11, 2021

@sallymorganmoore I tried submitting an issue for this as the extant issues I could find were either for different problems or errors in their reports. However, the issue report doesn't appear to have gone through correctly, possibly due to me trying to link to my video in the opening post via a text file, as they seem to have made it impossible to insert links (invalid characters) into the issue description.

If the issue I've described here hasn't been clearly identified and scheduled for a fix, could you pass it along to whom it may concern in the QA team? Thank you.

Morbad · Dec 11, 2021

Also, this is the Netlog (personal info redacted) from the session recorded above:

Code:

============================================
Logs/netLog.211210124855.01.log (part 1)
2021-12-10 12:48 Eastern Standard Time
============================================
{17:49:04GMT 9.889s} watchdog: 16 1260 2516 18219
{17:49:11GMT 16.186s} !!!--- Long frame time:  5.3 sec ---!!!
{17:49:37GMT 42.379s} [MM] StartMultiplayer
{17:49:37GMT 42.913s} WAN: 192.168.1.20:55045
{17:49:38GMT 43.639s} WAN: 192.168.1.20:55045
{17:49:38GMT 43.639s} Local machine is x, paddingMode=3, sentinels=0
{17:49:38GMT 43.639s} Machine: ''
{17:49:40GMT 45.276s} WebRequest: StatusCode=200; Request-Tag=69tbldh08r89m
{17:49:40GMT 45.277s} Webserver game version: Odyssey 4.0.0-dev.3 (version=400000100, dev=3)
{17:49:40GMT 45.277s} UID=112412 name=Backer #-12412
{17:50:06GMT 71.476s} [MultiCrewMatchmakingLog] RequestMatchmakingInfoIfNecessary: Requesting matchmaking info in 0.50 seconds.
{17:50:06GMT 71.963s} [MultiCrewMatchmakingLog] AdvanceInfoRequests: Sent matchmaking info request.
{17:50:06GMT 71.970s} UploadJournal: upload 2 lines of journal (163)
{17:50:06GMT 71.970s} [Wing] Wing - Resetting Wing
{17:50:06GMT 71.970s} [Wing] Wing - Clearing wing members
{17:50:07GMT 72.469s} [MultiCrewMatchmakingLog] AdvanceInfoRequests: Matchmaking info received: 4 (Bounty hunting, 0 ships), 8 (Piracy, 0 ships), 3 (Mentor a player, 0 ships), 16 (Xeno Hunting, 0 ships), 17 (Xeno Research, 0 ships), 1 (Further activities, 0 ships), 13 (Community Goals, 0 ships), 5 (Mining, 0 ships), 6 (Exploration, 0 ships), 9 (Smuggling, 0 ships), 14 (Support Ross 310 Natural Services, 0 ships), 15 (Support the Ross 310 Cartel, 0 ships)
{17:50:07GMT 72.469s} [MultiCrewMatchmakingLog] NotifyMatchmakingInfoUpdated
{17:50:07GMT 72.671s} IrcGameClient::InitActivate() - disabled
{17:50:09GMT 74.891s} WebRequest: StatusCode=200; Request-Tag=bwsv7qtcw9r1z
{17:50:09GMT 74.897s} [MultiCrewMatchmakingLog] OnGameExitComplete
{17:50:09GMT 74.897s} [MultiCrewMatchmakingLog] DeclineAllInvites
{17:50:10GMT 75.202s} ConnectToServerActivity: state=Init
{17:50:10GMT 75.202s} ConnectToServerActivity: state=WaitingForLogin
{17:50:10GMT 75.211s} GET server/list?protocol=13448&runID=x
{17:50:10GMT 75.211s} ConnectToServerActivity: state=GettingServerAddress
{17:50:10GMT 75.765s} WebRequest: StatusCode=200; Request-Tag=4l7rp3kn2mr4p
{17:50:11GMT 76.281s} WebRequest: StatusCode=200; Request-Tag=51fp31n1pt7b0
{17:50:11GMT 76.281s} Parsing servers. NumServers: 1(88205689602691,) Choice: 0
{17:50:11GMT 76.281s} New Primary Server: 88205689602691 x 8 [0/2]((34.243.126.216:19364))Name Unknown
{17:50:11GMT 76.281s} New Mission Server: 115014658893815 x 4 [0/2]((63.33.191.40:19364))Name Unknown
{17:50:11GMT 76.281s} ConnectToServerActivity: state=WaitingForConnection
{17:50:11GMT 76.285s} SyncAttempt:IPv4S: 0/1
{17:50:11GMT 76.285s} SyncAttempt:IPv4S: 0/2
{17:50:11GMT 76.320s} WebRequest: StatusCode=200; Request-Tag=9c6nrg90j1r52
{17:50:11GMT 76.408s} Warning: Connected to server built with different svn revision (r279123 vs local r279140). Machine: 88205689602691 x 7 [0/2]((34.243.126.216:19364))Name Unknown
{17:50:11GMT 76.408s} Now using address x.x.x.x:55045 for this machine after STUN reply
{17:50:11GMT 76.408s} Connected: 88205689602691 x 7 [0/2]((34.243.126.216:19364))EDServer#1540
{17:50:11GMT 76.408s} SyncSuccess:IPv4S: 1/2
{17:50:11GMT 76.408s} Warning: Connected to server built with different svn revision (r279123 vs local r279140). Machine: 115014658893815 x 5 [0/2]((63.33.191.40:19364))Name Unknown
{17:50:11GMT 76.408s} Connected: 115014658893815 x 5 [0/2]((63.33.191.40:19364))EDServer#1522
{17:50:11GMT 76.408s} SyncSuccess:IPv4S: 2/2
{17:50:11GMT 76.408s} ConnectToServerActivity: state=WaitingForIDAllocation
{17:50:11GMT 76.600s} Established IPv4 MTU=1500
{17:50:12GMT 76.990s} ConnectToServerActivity: state=WaitMtuDiscoveryComplete
{17:50:12GMT 76.990s} ConnectToServerActivity: state=StartPrimaryServer
{17:50:12GMT 76.990s} Machine::ConnectDirectly:unexpected state: state=3, m_attempt=0.000000, fdis=0: n Connected ** Comp:UDP LastRx=0.00s LastTx=0.24s SyncSent=0.12s  [1] Timeout Disc=0.00s Hold=30.00 srtt=125ms rttvar=48
{17:50:12GMT 76.990s} SendMyRoutingLetter: routing already established for this server
{17:50:12GMT 76.990s} ConnectToServerActivity: starting mission server
{17:50:12GMT 76.990s} Machine::ConnectDirectly:unexpected state: state=3, m_attempt=0.000000, fdis=0: n Connected ** Comp:UDP LastRx=0.49s LastTx=0.58s SyncSent=0.12s  [1] Timeout Disc=0.00s Hold=29.51 srtt=1000ms rttvar=0
{17:50:12GMT 76.990s} ConnectToServerActivity: state=WaitPrimaryServer
{17:50:12GMT 76.990s} IAddSession: 0x000000024bd1b71c: x x 3 ThisMachine Name Unknown
{17:50:12GMT 76.990s} IJoinSession:MyTalk: 0x000000024bd1b71c: x x 4 ThisMachine Name Unknown
{17:50:12GMT 77.215s} IAddSession: 0x000008f086425c07: x x 6 ThisMachine Name Unknown
{17:50:12GMT 77.215s} IJoinSession:Server (matchmaking): 0x000008f086425c07: x x 7 ThisMachine Name Unknown
{17:50:12GMT 77.224s} IAddSession: 0x000008f008905714: x x 7 ThisMachine Name Unknown
{17:50:12GMT 77.224s} IJoinSession:Server (missions): 0x000008f008905714: x x 8 ThisMachine Name Unknown
{17:50:12GMT 77.589s}  AddSession: 0x000008f086425c07: 88205689602691 x 9 [0/2]((34.243.126.216:19364))EDServer#1540
{17:50:12GMT 77.589s}  JoinSession:Server (matchmaking): 0x000008f086425c07: 88205689602691 x 9 [0/2]((34.243.126.216:19364))EDServer#1540
{17:50:12GMT 77.589s}  AddSession: 0x000008f008905714: 115014658893815 x 8 [0/2]((63.33.191.40:19364))EDServer#1522
{17:50:12GMT 77.589s}  JoinSession:Server (missions): 0x000008f008905714: 115014658893815 x 8 [0/2]((63.33.191.40:19364))EDServer#1522
{17:50:13GMT 77.980s} ConnectToServerActivity: state=Succeeded
{17:50:47GMT 112.272s} ConnectToServerActivity: state=Init
{17:50:47GMT 112.272s} ConnectToServerActivity: we are already connected
{17:50:47GMT 112.272s} ConnectToServerActivity: state=Succeeded
{17:51:11GMT 136.083s} ConnectToServerActivity: state=Init
{17:51:11GMT 136.083s} ConnectToServerActivity: we are already connected
{17:51:11GMT 136.083s} ConnectToServerActivity: state=Succeeded
{17:51:11GMT 136.168s} UploadJournal: upload 8 lines of journal (792)
{17:51:11GMT 136.171s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:51:11GMT 136.171s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:51:11GMT 136.171s} [Wing] Wing - Resetting Wing
{17:51:11GMT 136.171s} [Wing] Wing - Clearing wing members
{17:51:11GMT 136.171s} [Wing] Wing - Setting session ID 0x0 ...
{17:51:11GMT 136.171s} [Wing] ... server informed
{17:51:11GMT 136.171s} [Wing] Wing - Declined all invites
{17:51:11GMT 136.171s} [Wing] Wing - Commander (x) lock cleared
{17:51:11GMT 136.194s} Cancelling request 2e
{17:51:11GMT 136.292s} [Wing] Wing - Resetting Wing
{17:51:11GMT 136.292s} [Wing] Wing - Clearing wing members
{17:51:11GMT 136.353s} IrcGameClient::InitActivate() - disabled
{17:51:12GMT 137.608s} WebRequest: StatusCode=200; Request-Tag=2715k7kt6rvw8
{17:51:12GMT 137.613s} [MultiCrewMatchmakingLog] OnGameExitComplete
{17:51:12GMT 137.613s} [MultiCrewMatchmakingLog] DeclineAllInvites
{17:51:12GMT 137.719s} ConnectToServerActivity: state=Init
{17:51:12GMT 137.719s} ConnectToServerActivity: we are already connected
{17:51:12GMT 137.719s} ConnectToServerActivity: state=Succeeded
{17:51:13GMT 138.264s} WebRequest: StatusCode=200; Request-Tag=6ykdg10nf97td
{17:51:37GMT 162.499s} ConnectToServerActivity: state=Init
{17:51:37GMT 162.499s} ConnectToServerActivity: we are already connected
{17:51:37GMT 162.499s} ConnectToServerActivity: state=Succeeded
{17:51:38GMT 163.097s} WebRequest: StatusCode=200; Request-Tag=34f2w8klw559k
{17:51:46GMT 171.313s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:51:46GMT 171.313s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:51:46GMT 171.313s} [Wing] Wing - Resetting Wing
{17:51:46GMT 171.313s} [Wing] Wing - Clearing wing members
{17:51:46GMT 171.313s} [Wing] Wing - Setting session ID 0x0 ...
{17:51:46GMT 171.313s} [Wing] ... server informed
{17:51:46GMT 171.313s} [Wing] Wing - Declined all invites
{17:51:46GMT 171.313s} [Wing] Wing - Commander (x) lock cleared
{17:51:46GMT 171.319s} [Wing] Wing - Resetting Wing
{17:51:46GMT 171.319s} [Wing] Wing - Clearing wing members
{17:51:46GMT 171.322s} Cancelling request 40
{17:51:46GMT 171.378s} IrcGameClient::InitActivate() - disabled
{17:51:47GMT 172.414s} WebRequest: StatusCode=200; Request-Tag=48cgfk0y4922k
{17:51:48GMT 173.338s} Calculated hash of 0 for Human_Tiny_Military_01_Tutorial
{17:51:50GMT 175.654s} CPV: LoadingLocation_0x0000000100000057, Location_0x000000010000005a, 1, 2 (EnteringLocation)
{17:52:08GMT 193.051s} MissionManager now using server EDServer#1522
{17:52:08GMT 193.474s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.320s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.321s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:09GMT 194.745s} Execute Script Action
{17:52:10GMT 195.799s} Execute Script Action
{17:52:10GMT 195.799s} Execute Script Action
{17:52:10GMT 195.799s} Execute Script Action
{17:52:12GMT 197.065s} Execute Script Action
{17:52:12GMT 197.319s} Execute Script Action
{17:52:12GMT 197.319s} Execute Script Action
{17:52:12GMT 197.319s} Execute Script Action
{17:52:12GMT 197.319s} Execute Script Action
{17:52:13GMT 198.331s} Execute Script Action
{17:52:14GMT 199.232s} Execute Script Action
{17:52:14GMT 199.485s} Execute Script Action
{17:52:18GMT 203.535s} Execute Script Action
{17:52:19GMT 204.305s} Execute Script Action
{17:52:20GMT 205.064s} Execute Script Action
{17:52:20GMT 205.064s} Execute Script Action
{17:52:20GMT 205.064s} Execute Script Action
{17:52:24GMT 209.378s} Execute Script Action
{17:52:24GMT 209.884s} Execute Script Action
{17:52:25GMT 210.391s} Execute Script Action
{17:52:25GMT 210.391s} Execute Script Action
{17:52:29GMT 214.715s} Execute Script Action
{17:52:31GMT 215.981s} Execute Script Action
{17:52:31GMT 215.981s} Execute Script Action
{17:52:36GMT 221.056s} Execute Script Action
{17:52:36GMT 221.562s} Execute Script Action
{17:52:36GMT 221.562s} Execute Script Action
{17:52:42GMT 227.393s} Execute Script Action
{17:52:43GMT 228.403s} Execute Script Action
{17:52:43GMT 228.403s} Execute Script Action
{17:52:43GMT 228.403s} Execute Script Action
{17:52:43GMT 228.403s} Execute Script Action
{17:52:47GMT 232.970s} Execute Script Action
{17:52:47GMT 232.970s} Execute Script Action
{17:52:48GMT 233.729s} Execute Script Action
{17:52:49GMT 234.235s} Execute Script Action
{17:52:49GMT 234.235s} Execute Script Action
{17:52:55GMT 240.817s} Execute Script Action
{17:52:56GMT 241.070s} Execute Script Action
{17:52:56GMT 241.070s} Execute Script Action
{17:52:56GMT 241.070s} Execute Script Action
{17:53:01GMT 246.635s} Execute Script Action
{17:53:01GMT 246.889s} Execute Script Action
{17:53:01GMT 246.889s} Execute Script Action
{17:53:01GMT 246.889s} Execute Script Action
{17:53:09GMT 254.736s} Execute Script Action
{17:53:11GMT 256.001s} Execute Script Action
{17:53:11GMT 256.001s} Execute Script Action
{17:53:11GMT 256.001s} Execute Script Action
{17:53:11GMT 256.001s} Execute Script Action
{17:53:12GMT 257.267s} Execute Script Action
{17:53:12GMT 257.773s} Execute Script Action
{17:53:12GMT 257.773s} Execute Script Action
{17:53:16GMT 261.833s} Execute Script Action
{17:53:21GMT 266.388s} Execute Script Action
{17:53:21GMT 266.894s} Execute Script Action
{17:53:21GMT 266.894s} Execute Script Action
{17:53:27GMT 272.714s} Execute Script Action
{17:53:28GMT 273.473s} Execute Script Action
{17:53:28GMT 273.473s} Execute Script Action
{17:53:31GMT 276.511s} Execute Script Action
{17:53:31GMT 276.511s} Execute Script Action
{17:53:31GMT 276.764s} Execute Script Action
{17:53:32GMT 277.523s} Execute Script Action
{17:53:35GMT 280.308s} Execute Script Action
{17:53:41GMT 286.381s} Execute Script Action
{17:53:42GMT 287.405s} Execute Script Action
{17:53:42GMT 287.405s} Execute Script Action
{17:53:42GMT 287.405s} Execute Script Action
{17:53:42GMT 287.405s} Execute Script Action
{17:53:42GMT 287.405s} Execute Script Action
{17:53:43GMT 288.164s} Execute Script Action
{17:53:45GMT 290.949s} Execute Script Action
{17:53:45GMT 290.949s} Execute Script Action
{17:53:45GMT 290.949s} Execute Script Action
{17:53:45GMT 290.949s} Execute Script Action
{17:53:46GMT 291.962s} Execute Script Action
{17:53:46GMT 291.962s} Execute Script Action
{17:53:46GMT 291.962s} Execute Script Action
{17:53:49GMT 294.746s} Execute Script Action
{17:53:51GMT 296.011s} Execute Script Action
{17:53:51GMT 296.011s} Execute Script Action
{17:53:53GMT 298.794s} Execute Script Action
{17:53:53GMT 298.794s} Execute Script Action
{17:53:54GMT 299.047s} Execute Script Action
{17:53:54GMT 299.047s} Execute Script Action
{17:53:54GMT 299.047s} Execute Script Action
{17:53:54GMT 299.047s} Execute Script Action
{17:53:56GMT 301.832s} Execute Script Action
{17:53:56GMT 301.832s} Execute Script Action
{17:53:57GMT 302.338s} Execute Script Action
{17:54:11GMT 316.768s} Execute Script Action
{17:54:19GMT 324.868s} Execute Script Action
{17:54:19GMT 324.868s} Execute Script Action
{17:54:22GMT 327.398s} Execute Script Action
{17:54:26GMT 331.955s} Execute Script Action
{17:54:38GMT 343.852s} Execute Script Action
{17:54:38GMT 343.852s} Execute Script Action
{17:54:39GMT 344.106s} Execute Script Action
{17:54:40GMT 345.119s} Execute Script Action
{17:54:41GMT 346.134s} Execute Script Action
{17:54:41GMT 346.135s} Execute Script Action
{17:54:42GMT 347.899s} Execute Script Action
{17:54:43GMT 348.151s} Execute Script Action
{17:54:43GMT 348.407s} Execute Script Action
{17:54:50GMT 355.249s} Execute Script Action
{17:54:50GMT 355.249s} Execute Script Action
{17:54:54GMT 359.314s} Execute Script Action
{17:54:55GMT 360.333s} Execute Script Action
{17:54:58GMT 363.391s} Execute Script Action
{17:54:58GMT 363.391s} Execute Script Action
{17:55:02GMT 367.720s} Execute Script Action
{17:55:10GMT 375.344s} Execute Script Action
{17:55:24GMT 389.583s} Execute Script Action
{17:55:24GMT 389.583s} Execute Script Action
{17:55:28GMT 393.398s} Execute Script Action
{17:55:28GMT 393.398s} Execute Script Action
{17:55:43GMT 408.242s} Execute Script Action
{17:55:58GMT 423.488s} Execute Script Action
{17:56:13GMT 438.737s} Execute Script Action
{17:56:28GMT 453.970s} Execute Script Action
{17:56:44GMT 469.172s} Execute Script Action
{17:56:59GMT 484.346s} Execute Script Action
{17:57:08GMT 493.686s} Execute Script Action
{17:57:08GMT 493.686s} Execute Script Action
{17:57:10GMT 495.715s} Execute Script Action
{17:57:14GMT 499.510s} Execute Script Action
{17:57:29GMT 514.711s} Execute Script Action
{17:57:44GMT 529.863s} Execute Script Action
{17:58:00GMT 545.050s} Execute Script Action
{17:58:15GMT 560.271s} Execute Script Action
{17:58:30GMT 575.515s} Execute Script Action
{17:58:33GMT 578.172s} [MultiCrewMatchmakingLog] ForgetPreMultiCrewGameMode
{17:58:34GMT 579.187s} MissionManager disconnecting from server EDServer#1522
{17:58:34GMT 579.187s} UploadJournal: upload 2 lines of journal (161)
{17:58:34GMT 579.193s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:58:34GMT 579.193s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:58:34GMT 579.193s} [Wing] Wing - Resetting Wing
{17:58:34GMT 579.193s} [Wing] Wing - Clearing wing members
{17:58:34GMT 579.193s} [Wing] Wing - Setting session ID 0x0 ...
{17:58:34GMT 579.193s} [Wing] ... server informed
{17:58:34GMT 579.193s} [Wing] Wing - Declined all invites
{17:58:34GMT 579.193s} [Wing] Wing - Commander (x) lock cleared
{17:58:34GMT 579.262s} Cancelling request 4b
{17:58:34GMT 579.298s} [Wing] Wing - Resetting Wing
{17:58:34GMT 579.298s} [Wing] Wing - Clearing wing members
{17:58:34GMT 579.383s} IrcGameClient::InitActivate() - disabled
{17:58:35GMT 580.700s} WebRequest: StatusCode=200; Request-Tag=5rn0m5h847fgj
{17:58:35GMT 580.705s} [MultiCrewMatchmakingLog] OnGameExitComplete
{17:58:35GMT 580.705s} [MultiCrewMatchmakingLog] DeclineAllInvites
{17:58:35GMT 580.811s} ConnectToServerActivity: state=Init
{17:58:35GMT 580.811s} ConnectToServerActivity: we are already connected
{17:58:35GMT 580.811s} ConnectToServerActivity: state=Succeeded
{17:58:36GMT 581.899s} WebRequest: StatusCode=200; Request-Tag=vx2kj6hj1qdch
{17:58:39GMT 584.322s} UploadJournal: upload 1 lines of journal (82)
{17:58:39GMT 584.324s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:58:39GMT 584.324s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:58:39GMT 584.324s} [Wing] Wing - Resetting Wing
{17:58:39GMT 584.324s} [Wing] Wing - Clearing wing members
{17:58:39GMT 584.324s} [Wing] Wing - Setting session ID 0x0 ...
{17:58:39GMT 584.324s} [Wing] ... server informed
{17:58:39GMT 584.324s} [Wing] Wing - Declined all invites
{17:58:39GMT 584.324s} [Wing] Wing - Commander (x) lock cleared
{17:58:39GMT 584.331s} [Wing] Wing - Resetting Wing
{17:58:39GMT 584.331s} [Wing] Wing - Clearing wing members
{17:58:39GMT 584.346s} Cancelling request 6e
{17:58:39GMT 584.397s} IrcGameClient::InitActivate() - disabled
{17:58:40GMT 585.437s} WebRequest: StatusCode=200; Request-Tag=14b5tjgmyrm4k
{17:58:40GMT 585.664s} Calculated hash of 0 for Human_Tiny_Military_01_Tutorial
{17:58:41GMT 586.544s} CPV: LoadingLocation_0x00000001000004d8, Location_0x00000001000004db, 1, 2 (EnteringLocation)
{17:59:00GMT 605.967s} MissionManager now using server EDServer#1522
{17:59:01GMT 606.382s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.205s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:02GMT 607.458s} Execute Script Action
{17:59:03GMT 608.472s} Execute Script Action
{17:59:03GMT 608.472s} Execute Script Action
{17:59:03GMT 608.472s} Execute Script Action
{17:59:03GMT 608.734s} Execute Script Action
{17:59:04GMT 608.987s} Execute Script Action
{17:59:04GMT 608.987s} Execute Script Action
{17:59:04GMT 608.987s} Execute Script Action
{17:59:04GMT 608.987s} Execute Script Action
{17:59:05GMT 610.000s} Execute Script Action
{17:59:06GMT 611.013s} Execute Script Action
{17:59:07GMT 612.279s} Execute Script Action
{17:59:09GMT 614.050s} Execute Script Action
{17:59:09GMT 614.304s} Execute Script Action
{17:59:12GMT 617.593s} Execute Script Action
{17:59:12GMT 617.593s} Execute Script Action
{17:59:12GMT 617.593s} Execute Script Action
{17:59:17GMT 622.655s} Execute Script Action
{17:59:17GMT 622.855s} machines=2&numturnlinks=0&backlogtotal=0&backlogmax=0&avgsrtt=207&maxLoss=0.000&avgLoss=0.000&act1=38.631&act2=0.100
{17:59:18GMT 623.161s} Execute Script Action
{17:59:18GMT 623.161s} Execute Script Action
{17:59:21GMT 626.959s} Execute Script Action
{17:59:22GMT 627.718s} Execute Script Action
{17:59:24GMT 628.984s} Execute Script Action
{17:59:24GMT 628.984s} Execute Script Action
{17:59:28GMT 633.541s} Execute Script Action
{17:59:28GMT 633.541s} Execute Script Action
{17:59:31GMT 636.757s} [MultiCrewMatchmakingLog] ForgetPreMultiCrewGameMode
{17:59:32GMT 637.860s} MissionManager disconnecting from server EDServer#1522
{17:59:32GMT 637.860s} UploadJournal: upload 2 lines of journal (161)
{17:59:32GMT 637.865s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:59:32GMT 637.866s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:59:32GMT 637.866s} [Wing] Wing - Resetting Wing
{17:59:32GMT 637.866s} [Wing] Wing - Clearing wing members
{17:59:32GMT 637.866s} [Wing] Wing - Setting session ID 0x0 ...
{17:59:32GMT 637.866s} [Wing] ... server informed
{17:59:32GMT 637.866s} [Wing] Wing - Declined all invites
{17:59:32GMT 637.866s} [Wing] Wing - Commander (x) lock cleared
{17:59:32GMT 637.939s} Cancelling request 75
{17:59:32GMT 637.975s} [Wing] Wing - Resetting Wing
{17:59:32GMT 637.975s} [Wing] Wing - Clearing wing members
{17:59:33GMT 638.060s} IrcGameClient::InitActivate() - disabled
{17:59:34GMT 639.136s} WebRequest: StatusCode=200; Request-Tag=3f10ck1crw8w1
{17:59:34GMT 639.344s} [MultiCrewMatchmakingLog] OnGameExitComplete
{17:59:34GMT 639.344s} [MultiCrewMatchmakingLog] DeclineAllInvites
{17:59:34GMT 639.452s} ConnectToServerActivity: state=Init
{17:59:34GMT 639.452s} ConnectToServerActivity: we are already connected
{17:59:34GMT 639.452s} ConnectToServerActivity: state=Succeeded
{17:59:35GMT 640.553s} WebRequest: StatusCode=200; Request-Tag=203rhsr3rczhn
{17:59:50GMT 655.371s} ConnectToServerActivity: state=Init
{17:59:50GMT 655.371s} ConnectToServerActivity: we are already connected
{17:59:50GMT 655.371s} ConnectToServerActivity: state=Succeeded
{17:59:56GMT 661.053s} UploadJournal: upload 1 lines of journal (82)
{17:59:56GMT 661.056s} IrcGameClient::Shutdown_Deactivate() - was disabled
{17:59:56GMT 661.057s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{17:59:56GMT 661.057s} [Wing] Wing - Resetting Wing
{17:59:56GMT 661.057s} [Wing] Wing - Clearing wing members
{17:59:56GMT 661.057s} [Wing] Wing - Setting session ID 0x0 ...
{17:59:56GMT 661.057s} [Wing] ... server informed
{17:59:56GMT 661.057s} [Wing] Wing - Declined all invites
{17:59:56GMT 661.057s} [Wing] Wing - Commander (x) lock cleared
{17:59:56GMT 661.064s} [Wing] Wing - Resetting Wing
{17:59:56GMT 661.064s} [Wing] Wing - Clearing wing members
{17:59:56GMT 661.079s} Cancelling request 83
{17:59:56GMT 661.140s} IrcGameClient::InitActivate() - disabled
{17:59:57GMT 662.303s} WebRequest: StatusCode=200; Request-Tag=7bsc1wzkmnvyg
{17:59:57GMT 662.526s} Calculated hash of 0 for Human_Tiny_Military_01_Tutorial
{17:59:58GMT 663.340s} CPV: LoadingLocation_0x0000000100000957, Location_0x000000010000095a, 1, 2 (EnteringLocation)
{18:00:17GMT 682.833s} MissionManager now using server EDServer#1522
{18:00:18GMT 683.261s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.072s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:19GMT 684.326s} Execute Script Action
{18:00:20GMT 685.338s} Execute Script Action
{18:00:20GMT 685.338s} Execute Script Action
{18:00:20GMT 685.338s} Execute Script Action
{18:00:20GMT 685.590s} Execute Script Action
{18:00:20GMT 685.843s} Execute Script Action
{18:00:20GMT 685.843s} Execute Script Action
{18:00:20GMT 685.844s} Execute Script Action
{18:00:20GMT 685.844s} Execute Script Action
{18:00:21GMT 686.856s} Execute Script Action
{18:00:22GMT 687.868s} Execute Script Action
{18:00:24GMT 689.134s} Execute Script Action
{18:00:27GMT 692.931s} Execute Script Action
{18:00:28GMT 693.184s} Execute Script Action
{18:00:29GMT 694.450s} Execute Script Action
{18:00:29GMT 694.450s} Execute Script Action
{18:00:29GMT 694.450s} Execute Script Action
{18:00:34GMT 699.257s} Execute Script Action
{18:00:34GMT 699.764s} Execute Script Action
{18:00:34GMT 699.764s} Execute Script Action
{18:00:37GMT 702.802s} Execute Script Action
{18:00:39GMT 704.067s} Execute Script Action
{18:00:40GMT 705.333s} Execute Script Action
{18:00:40GMT 705.333s} Execute Script Action
{18:00:45GMT 710.393s} Execute Script Action
{18:00:45GMT 710.900s} Execute Script Action
{18:00:45GMT 710.900s} Execute Script Action
{18:00:51GMT 716.733s} Execute Script Action
{18:00:52GMT 717.746s} Execute Script Action
{18:00:52GMT 717.746s} Execute Script Action
{18:00:52GMT 717.746s} Execute Script Action
{18:00:52GMT 717.746s} Execute Script Action
{18:00:57GMT 722.567s} Execute Script Action
{18:00:57GMT 722.567s} Execute Script Action
{18:00:58GMT 723.073s} Execute Script Action
{18:01:20GMT 745.598s} Execute Script Action
{18:01:24GMT 749.649s} Execute Script Action
{18:01:24GMT 749.649s} Execute Script Action
{18:01:26GMT 751.926s} Execute Script Action
{18:02:45GMT 830.640s} Execute Script Action
{18:02:48GMT 833.931s} Execute Script Action
{18:02:49GMT 834.691s} Execute Script Action
{18:02:51GMT 836.970s} Execute Script Action
{18:02:52GMT 837.729s} Execute Script Action
{18:03:10GMT 855.193s} Execute Script Action
{18:03:37GMT 882.527s} Execute Script Action
{18:03:38GMT 883.792s} Execute Script Action
{18:04:13GMT 918.973s} Execute Script Action
{18:04:13GMT 918.973s} Execute Script Action
{18:04:20GMT 925.806s} Execute Script Action
{18:04:21GMT 926.059s} Execute Script Action
{18:04:21GMT 926.059s} Execute Script Action
{18:04:21GMT 926.059s} Execute Script Action
{18:04:26GMT 931.881s} Execute Script Action
{18:04:27GMT 932.134s} Execute Script Action
{18:04:27GMT 932.134s} Execute Script Action
{18:04:27GMT 932.134s} Execute Script Action
{18:04:34GMT 939.220s} Execute Script Action
{18:04:34GMT 939.221s} Execute Script Action
{18:04:34GMT 939.221s} Execute Script Action
{18:04:34GMT 939.221s} Execute Script Action
{18:04:35GMT 940.232s} Execute Script Action
{18:04:38GMT 943.270s} Execute Script Action
{18:04:38GMT 943.270s} Execute Script Action
{18:04:42GMT 947.572s} Execute Script Action
{18:04:47GMT 952.383s} Execute Script Action
{18:04:47GMT 952.889s} Execute Script Action
{18:04:47GMT 952.889s} Execute Script Action
{18:04:52GMT 957.447s} Execute Script Action
{18:04:52GMT 957.447s} Execute Script Action
{18:04:53GMT 958.460s} Execute Script Action
{18:04:53GMT 958.966s} Execute Script Action
{18:05:00GMT 965.294s} Execute Script Action
{18:05:01GMT 966.053s} Execute Script Action
{18:05:01GMT 966.306s} Execute Script Action
{18:05:01GMT 966.306s} Execute Script Action
{18:05:01GMT 966.306s} Execute Script Action
{18:05:01GMT 966.306s} Execute Script Action
{18:05:01GMT 966.306s} Execute Script Action
{18:05:04GMT 969.851s} Execute Script Action
{18:05:04GMT 969.851s} Execute Script Action
{18:05:34GMT 999.213s} Execute Script Action
{18:05:35GMT 1000.479s} Execute Script Action
{18:05:35GMT 1000.479s} Execute Script Action
{18:05:37GMT 1002.757s} Execute Script Action
{18:05:37GMT 1002.757s} Execute Script Action
{18:05:40GMT 1005.033s} Execute Script Action
{18:05:40GMT 1005.033s} Execute Script Action
{18:05:50GMT 1015.410s} Execute Script Action
{18:05:50GMT 1015.410s} Execute Script Action
{18:05:53GMT 1018.700s} Execute Script Action
{18:05:56GMT 1021.738s} Execute Script Action
{18:06:10GMT 1035.154s} Execute Script Action
{18:06:10GMT 1035.154s} Execute Script Action
{18:06:17GMT 1041.989s} Execute Script Action
{18:06:20GMT 1045.787s} Execute Script Action
{18:06:20GMT 1045.787s} Execute Script Action
{18:06:25GMT 1050.091s} Execute Script Action
{18:06:39GMT 1064.276s} Execute Script Action
{18:06:39GMT 1064.528s} Execute Script Action
{18:06:57GMT 1082.005s} Execute Script Action
{18:06:58GMT 1083.522s} Execute Script Action
{18:06:58GMT 1083.522s} Execute Script Action
{18:06:59GMT 1084.789s} Execute Script Action
{18:06:59GMT 1084.789s} Execute Script Action
{18:07:01GMT 1086.053s} Execute Script Action
{18:07:01GMT 1086.053s} Execute Script Action
{18:07:06GMT 1091.116s} Execute Script Action
{18:07:06GMT 1091.116s} Execute Script Action
{18:07:07GMT 1092.888s} Execute Script Action
{18:07:08GMT 1093.395s} Execute Script Action
{18:07:10GMT 1095.420s} Execute Script Action
{18:07:10GMT 1095.420s} Execute Script Action
{18:07:13GMT 1098.710s} Execute Script Action
{18:07:15GMT 1100.482s} Execute Script Action
{18:07:15GMT 1100.482s} Execute Script Action
{18:07:17GMT 1102.002s} Execute Script Action
{18:07:17GMT 1102.002s} Execute Script Action
{18:07:17GMT 1102.002s} Execute Script Action
{18:07:17GMT 1102.002s} Execute Script Action
{18:07:17GMT 1102.002s} Execute Script Action
{18:07:20GMT 1105.799s} Execute Script Action
{18:07:21GMT 1106.558s} Execute Script Action
{18:07:27GMT 1112.127s} Execute Script Action
{18:07:27GMT 1112.127s} Execute Script Action
{18:07:27GMT 1112.127s} Execute Script Action
{18:07:27GMT 1112.127s} Execute Script Action
{18:07:27GMT 1112.379s} Execute Script Action
{18:07:28GMT 1113.899s} Execute Script Action
{18:08:44GMT 1189.242s} NeedToSendAck(e686,e686,0) for 88205689602691 x 10 [0/2]((34.243.126.216:19364))EDServer#1540 increased to 1.20, schedvalue=10.17
{18:09:17GMT 1222.831s} machines=2&numturnlinks=0&backlogtotal=0&backlogmax=0&avgsrtt=251&maxLoss=0.000&avgLoss=0.000&act1=35.694&act2=0.102
{18:09:37GMT 1242.607s} Execute Script Action
{18:09:37GMT 1242.607s} Execute Script Action
{18:09:38GMT 1243.626s} Execute Script Action
{18:09:38GMT 1243.626s} Execute Script Action
{18:09:38GMT 1243.626s} Execute Script Action
{18:09:41GMT 1246.427s} Execute Script Action
{18:09:42GMT 1247.690s} Execute Script Action
{18:09:42GMT 1247.690s} Execute Script Action
{18:09:45GMT 1250.517s} Execute Script Action
{18:09:45GMT 1250.517s} Execute Script Action
{18:09:45GMT 1250.768s} Execute Script Action
{18:09:45GMT 1250.768s} Execute Script Action
{18:09:45GMT 1250.768s} Execute Script Action
{18:09:45GMT 1250.768s} Execute Script Action
{18:09:48GMT 1253.560s} Execute Script Action
{18:09:48GMT 1253.560s} Execute Script Action
{18:09:49GMT 1254.070s} Execute Script Action
{18:10:03GMT 1268.555s} Execute Script Action
{18:10:05GMT 1270.432s} [MultiCrewMatchmakingLog] ForgetPreMultiCrewGameMode
{18:10:06GMT 1271.503s} MissionManager disconnecting from server EDServer#1522
{18:10:06GMT 1271.503s} UploadJournal: upload 2 lines of journal (161)
{18:10:06GMT 1271.509s} IrcGameClient::Shutdown_Deactivate() - was disabled
{18:10:06GMT 1271.510s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{18:10:06GMT 1271.510s} [Wing] Wing - Resetting Wing
{18:10:06GMT 1271.510s} [Wing] Wing - Clearing wing members
{18:10:06GMT 1271.510s} [Wing] Wing - Setting session ID 0x0 ...
{18:10:06GMT 1271.510s} [Wing] ... server informed
{18:10:06GMT 1271.510s} [Wing] Wing - Declined all invites
{18:10:06GMT 1271.510s} [Wing] Wing - Commander (x) lock cleared
{18:10:06GMT 1271.552s} Cancelling request 8b
{18:10:09GMT 1274.072s} Cleared Mission Server
{18:10:09GMT 1274.072s} ILeftSession(3): 0x000008f008905714: x x 8 ThisMachine Name Unknown
{18:10:09GMT 1274.072s}  LeftSession(6): 0x000008f008905714: 115014658893815 x 9 [0/2]((63.33.191.40:19364))EDServer#1522
{18:10:09GMT 1274.072s} [Wing] Wing - Leaving.  DropRecievedInvites = True, keepWingProxy = False
{18:10:09GMT 1274.072s} [Wing] Wing - Resetting Wing
{18:10:09GMT 1274.072s} [Wing] Wing - Clearing wing members
{18:10:09GMT 1274.072s} [Wing] Wing - Setting session ID 0x0 ...
{18:10:09GMT 1274.072s} [Wing] ... server informed
{18:10:09GMT 1274.072s} [Wing] Wing - Declined all invites
{18:10:09GMT 1274.072s} [Wing] Wing - Commander (x) lock cleared
{18:10:09GMT 1274.109s} CentralServerService:Logout - Starting logout
{18:10:09GMT 1274.109s} ILeftSession(3): 0x000008f086425c07: x x 7 ThisMachine Name Unknown
{18:10:09GMT 1274.109s}  LeftSession(6): 0x000008f086425c07: 88205689602691 x 10 [0/2]((34.243.126.216:19364))EDServer#1540
{18:10:09GMT 1274.109s} CentralServerService:Logout - RemoveThisMachine from each session
{18:10:09GMT 1274.109s} ILeftSession(3): 0x000000024bd1b71c: x x 6 ThisMachine Name Unknown
 18:10:09GMT 1274.112s} Machine 115014658893815 x 4 [0/2]((63.33.191.40:19364))EDServer#1522 disconnected: ServerLink::~
{18:10:09GMT 1274.112s} allow machine to time out immediately after forced disconnect (ed-31797)
{18:10:09GMT 1274.112s} Machine 88205689602691 x 5 [0/2]((34.243.126.216:19364))EDServer#1540 disconnected: ServerLink::~
{18:10:09GMT 1274.112s} allow machine to time out immediately after forced disconnect (ed-31797)
{18:10:09GMT 1274.878s} Machine x x 2 ThisMachine Name Unknown disconnected: shutdown

Not much detail (will check logging verbosity later), but a whole lot of "Execute Script Action" that may or may not be related to something.

Globusdiablo · Dec 11, 2021

Morbad said:
...In hindsight, this issue has been around since at least U7, but was burried under so many other performance problems that I was not able to isolate it. This has likely been behind reports of poor performance until settlements were partially cleared...

Dr. Livingstone, I presume?

Wendake · Dec 11, 2021

I also have worst performance since U9. Got drops under 20 in concourse and settlements which wasn't a problem anymore since U8.

Frop · Dec 11, 2021

I'd like to add that some of the settlement loading freezes (before the NPCs actually start to move) seem to have returned for some of the more problematic settlement layouts.

Morbad · Dec 13, 2021

This issue is unchanged in 9.01.

Mogen Teras · Dec 14, 2021

Morbad said:
This issue is unchanged in 9.01.

I have also noticed this behaviour.

I have a 6 core 8600K, no multithreading.

Well first off, any time I enter a settlement, there's a roughly 90% chance the game flatout crashes to desktop within 60 seconds of entering it's near vicinity.

BUT, on the 10% chance it DOESN'T crash, I noticed that CPU usage shot up to 100% on all 6 threads, knocking my framerate down to about 30-40, and would just stay pegged at all-core max load for an indeterminate amount of time. Then something would just click, and it would go down to roughly 60% load on each core. The amount of time it took for it to calm down was always different.

I seriously doubt that there is something going on in settlements that is so computationally advanced as to completely max out my CPU entirely on every single core, so it definitely is due to something being fundamentally broken.

Morbad · Dec 14, 2021

I haven't had any crashes in U9+, yet, except where I had clearly broken something with my own meddling, but I strongly suspect there is some sort of script activity that the game gets stuck on when entering the vicinity of settlements.

Andro · Dec 15, 2021

I hope they're aware of this.

St0rmFury · Dec 15, 2021

6 months and 9 updates later and here we are.

Zwei34 · Dec 15, 2021

Similarly, I have noticed when streaming something (twitch, youtube, etc.) on a second monitor, right when I land (before I even disembark) I get massive stuttering and browser lockups, all cores are spiked -- if I click on the browser window, the stream returns to normal, but clicking back on the game caused it to stutter again. This occurs for about 30-60 seconds, then seems to recover on its own. If the behavior does not occur, I've noticed that all the NPCs are frozen in their spawn locations and will not move (which is another known issue, I believe).

I have witnessed the CPU spike occurs at any location where NPCs are walking about on a planet -- it obviously gets more noticeable the more NPCs are spawned.

This has happened since the 9.01 update for me. Core i5-8600K 3.6GHz, Windows 11.

Screenshot of the spike (landed at Cavalcante Engineering Base ++ [Medium Security] in HIP 14211):

Mogen Teras · Dec 15, 2021

St0rmFury said:
6 months and 9 updates later and here we are.

Yeah my faith is starting to really wane now. I remember back in September recommending to those that hadn't bought it yet to wait until January because I assumed everything would be sorted by then.

It's December now and it feels just as broken as did on Day 1, just in different ways now.

Pigletslastand · Dec 15, 2021

Zwei34 said:
Similarly, I have noticed when streaming something (twitch, youtube, etc.) on a second monitor, right when I land (before I even disembark) I get massive stuttering and browser lockups, all cores are spiked -- if I click on the browser window, the stream returns to normal, but clicking back on the game caused it to stutter again. This occurs for about 30-60 seconds, then seems to recover on its own. If the behavior does not occur, I've noticed that all the NPCs are frozen in their spawn locations and will not move (which is another known issue, I believe).

I have witnessed the CPU spike occurs at any location where NPCs are walking about on a planet -- it obviously gets more noticeable the more NPCs are spawned.

This has happened since the 9.01 update for me. Core i5-8600K 3.6GHz, Windows 11.

Screenshot of the spike (landed at Cavalcante Engineering Base ++ [Medium Security] in HIP 14211):
View attachment 280935

How many SSDs ya got man?!

Zwei34 · Dec 15, 2021

Pigletslastand said:
How many SSDs ya got man?!

Only 5.

This is what happens when you go cheap and buy the 256GB size each time and run out of space continually from other games like Assassin's Creed that take up half the drive...