Archived Delta too great

ronnietucker · Sep 5, 2015

Everything seems to go swimmingly. The game is totally playable. Then, after a while of noodling around crafting and digging I keep getting 'Delta too great' showing up in my terminal (which is how I start Terasology in Ubuntu 15.04) and my FPS drops to about 2. The game becomes unplayable with pauses for several seconds at a time.

Is there something I can try to stop this slow down/freezing occuring?

Cervator · Sep 5, 2015

"Delta too great" is just a log entry that tells you the game is lagging and skipping through time in an attempt to keep up. The cause is something else.

With debug mode up like you have it there press F4 to get a list of processes and the amount of ms they are consuming. Whatever is on top is likely going to be the culprit.

How do the memory stats you see in the debug text handle? Does memory keep rising and dropping rapidly? TTA is fairly processing and memory intensive and may have some memory leaks. You could try launching the game with more memory assigned to see if that'll help some or at least make it take longer to drift into single-digit FPS land. If it is a memory leak returning to the main menu then reloading should help reset and keep you going a bit longer.

Lowering the view distance should also be a major help, but that can get kinda boring fast with only a tiny bit of world visible. You can do that in-game with the HOME and END keys.

ronnietucker · Sep 5, 2015

Lowering the view distance a touch does seem to keep the FPS in double figures. It definitely stutters when saving though.

But so far:

ronnietucker · Sep 5, 2015

Ooh. It's a hungry beast. I tried starting it with 512 RAM with a max of 2GB and:

Cervator · Sep 5, 2015

Render Overlays and HealthSystem are taking substantial time? That's a first - I've never even seen the HealthSystem make the chart and am unsure if I've ever seen that rendering category. That's kind of good news - likely there's something hugely inefficient we can fix

Drawback though, and the reason the triggers might not all be in great shape, is that @Marcin Sciesinski hasn't had much time to spend on TTA lately. If he's out there somewhere he might be able to take a look and figure something out.

Curiously, do you do something in the world before those two categories show up and cause the FPS to drop? As in, if you start a new TTA world are they present right away or do they only appear after a period of time, like after your hunger bar reaches empty, or you have placed some particular objects in the world? Do they change if you leave your base and only have pristine wilderness loaded?

ronnietucker · Sep 5, 2015

Curiouser and curiouser!

So, I started a new TTA game. Everything was as expected:

So I noodled around a bit getting some twigs, sticks, axe, trunks etc. By this point, I've placed some stone blocks that I've made. Still everything is rosy:

Then things start going awry. I exited the game back to the menu and loaded up my save game again. Now, I have items, but they're not showing:

I scratch my head for a bit then the game crashes. I forgot to keep the crash log, but I restarted the game and my stuff was back. But... it's BA-AAAACK!:

After a few moments it crashed again. This time the log file is at: http://pastebin.com/et0kWcWP

My terminal was showing stuff (not sure if this is helpful) like:

Code:

20:48:00.310 [main] INFO  o.t.w.b.internal.BlockManagerImpl - Registered BlockFamily[Core:Sand]
20:48:00.311 [main] INFO  o.t.w.b.internal.BlockManagerImpl - Registered Block Core:Sand with id 174
20:48:00.343 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (5134), capping to 1000
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome MOUNTAINS with short id 0.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome SNOW with short id 1.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome DESERT with short id 2.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome FOREST with short id 3.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome OCEAN with short id 4.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome BEACH with short id 5.
20:48:00.351 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome PLAINS with short id 6.
20:48:00.355 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@4abfe2d2 with short id 7.
20:48:00.355 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@70d781b8 with short id 8.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@6a8d6209 with short id 9.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@2bcb5562 with short id 10.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@1ed78bbb with short id 11.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@52c10398 with short id 12.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.anotherWorld.AnotherWorldBiomeImpl@53ddbee3 with short id 13.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Registering biome org.terasology.world.biomes.UnknownBiome@59303963 with short id 0.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:ocean with short id 4 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:beach with short id 2 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Forest with short id 7 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:desert with short id 8 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:snow with short id 11 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:plains with short id 12 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:mountains with short id 1 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Taiga with short id 13 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome Core:forest with short id 6 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Tundra with short id 3 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Desert with short id 9 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Plains with short id 5 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Cliff with short id 10 from save game.
20:48:00.356 [main] INFO  o.t.world.biomes.BiomeManager - Restored biome AnotherWorld:Alpine with short id 14 from save game.
20:48:00.556 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.592 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.680 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.697 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.703 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.747 [main] WARN  o.t.p.s.ComponentSerializer - Cannot deserialize unknown field 'groundFriction' onto 'engine:CharacterMovement'
20:48:00.747 [main] WARN  o.t.p.s.ComponentSerializer - Cannot deserialize unknown field 'maxGroundSpeed' onto 'engine:CharacterMovement'
20:48:00.747 [main] WARN  o.t.p.s.ComponentSerializer - Cannot deserialize unknown field 'distanceBetweenSwimStrokes' onto 'engine:CharacterMovement'
20:48:00.747 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'Breather'
20:48:00.768 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.777 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.785 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.787 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'AccessInventoryAction'
20:48:00.787 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.788 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.799 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.831 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:00.850 [main] ERROR o.t.p.serializers.PrefabSerializer - Prefab contains unknown component 'ChangingBlocks'
20:48:01.247 [main] ERROR o.t.e.event.internal.EventSystemImpl - Invalid event handler method: componentUpdated
20:48:01.247 [main] ERROR o.t.e.event.internal.EventSystemImpl - Invalid event handler method: componentUpdated
20:48:02.102 [main] INFO  o.t.e.m.l.InitialiseWorld - World seed: "v4SBwb05gSnUFEXXvRuZjg08tnyi4Auv"
20:48:02.321 [main] INFO  org.terasology.seasons.SeasonSystem - Initializing SeasonSystem - org.terasology.world.time.WorldTimeImpl@6c34a330 1.0213210582733154 1.0213210582733154
20:48:04.933 [main] WARN  o.t.l.nameTags.NameTagClientSystem - Can't create player based name tag for character as owner has no client component
20:48:04.951 [main] INFO  o.t.r.world.RenderableWorldImpl - New Viewing Distance: Near
20:48:11.204 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1105), capping to 1000
20:49:11.032 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
20:49:11.035 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
20:49:12.145 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
20:50:11.038 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
20:50:11.040 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
20:50:12.180 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
20:50:14.007 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
20:50:14.009 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
20:50:14.009 [main] INFO  o.t.n.internal.NetworkSystemImpl - Client disconnected: ronnie
20:50:14.011 [main] INFO  o.t.logic.console.ConsoleImpl - [NOTIFICATION] Player "ronnie" has left the game
20:50:14.014 [main] INFO  o.t.n.internal.NetworkSystemImpl - Network shutdown
20:50:15.245 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
20:50:15.245 [main] WARN  org.reflections.Reflections - given scan urls are empty. set urls in the configuration
20:50:15.423 [main] ERROR o.terasology.engine.TerasologyEngine - Uncaught exception, attempting clean game shutdown
java.lang.NullPointerException: null
    at org.terasology.rendering.opengl.OpenGLTexture.getData(OpenGLTexture.java:178) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:74) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:62) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshDataProducer.getAssetData(IconMeshDataProducer.java:80) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.assets.AssetType.reloadFromProducers(AssetType.java:467) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.AssetType.refresh(AssetType.java:155) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.module.ModuleAwareAssetTypeManager.switchEnvironment(ModuleAwareAssetTypeManager.java:389) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.cheapAssetManagerUpdate(EnvironmentSwitchHandler.java:128) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.handleSwitchToEmptyEnivronment(EnvironmentSwitchHandler.java:149) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.modes.StateIngame.dispose(StateIngame.java:157) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.TerasologyEngine.switchState(TerasologyEngine.java:495) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.TerasologyEngine.processPendingState(TerasologyEngine.java:488) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.TerasologyEngine.mainLoop(TerasologyEngine.java:393) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.TerasologyEngine.run(TerasologyEngine.java:366) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.Terasology.main(Terasology.java:153) [Terasology.jar:57, origin/master, 57]
20:50:15.424 [main] INFO  o.terasology.engine.TerasologyEngine - Shutting down Terasology...
20:50:15.424 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
20:50:15.425 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
20:50:15.425 [main] INFO  o.t.n.internal.NetworkSystemImpl - Network shutdown
20:50:15.430 [main] WARN  org.reflections.Reflections - given scan urls are empty. set urls in the configuration
20:50:15.599 [main] ERROR o.terasology.engine.TerasologyEngine - Clean game shutdown after an uncaught exception failed
java.lang.NullPointerException: null
    at org.terasology.rendering.opengl.OpenGLTexture.getData(OpenGLTexture.java:178) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:74) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:62) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshDataProducer.getAssetData(IconMeshDataProducer.java:80) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.assets.AssetType.reloadFromProducers(AssetType.java:467) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.AssetType.refresh(AssetType.java:155) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.module.ModuleAwareAssetTypeManager.switchEnvironment(ModuleAwareAssetTypeManager.java:389) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.cheapAssetManagerUpdate(EnvironmentSwitchHandler.java:128) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.handleSwitchToEmptyEnivronment(EnvironmentSwitchHandler.java:149) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.modes.StateIngame.dispose(StateIngame.java:157) ~[engine-0.54.1.jar:57, master, 57, pre-alpha]
    at org.terasology.engine.TerasologyEngine.cleanup(TerasologyEngine.java:435) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.TerasologyEngine.run(TerasologyEngine.java:372) ~[engine-0.54.1.jar:57, origin/master, 57]
    at org.terasology.engine.Terasology.main(Terasology.java:153) [Terasology.jar:57, origin/master, 57]

It doesn't always crash, but when it does I have to either CTRL+C (in the terminal) or kill Java using the System Monitor.

Cervator · Sep 5, 2015

It looks like you're hitting https://github.com/MovingBlocks/Terasology/issues/1855 - an issue related to graphic assets particularly experienced with TTA.

It is fixed in the latest dev build, but not yet released. You could try the dev build from Jenkins and see if that helps

ronnietucker · Sep 5, 2015

I'll give that a bash. What could possibly go wrong...

ronnietucker · Sep 5, 2015

Well...

For the lulz I tried the game I'd been using before (now in the DEV version) and, sure enough, it loaded up and still had your friends health and overlay at the top of the list.

also, using the dev version, I created a new game and did my usual, like last time. Gathered some bits, made some tools, and then as soon as I exited to the menu it crashed.

Only now do I realise there's three log files. My bad!

game.log:
https://docs.google.com/uc?id=0B5l2RT_UOCXRT2J6cnBkSE45QUE&export=download

init.log
https://docs.google.com/uc?id=0B5l2RT_UOCXRcENZUGcyeHVuMlk&export=download

You may wonder why I didn't use Pastebin. Well, when I try the crash handler comes up with a popup that says:

UPLOAD FAILED
Failed to fetch contents from link: java.io.IOException: Server returned HTTP response code: 502 for URL: http://pastebin.com/api/api_post.php

I'm beginning to feel like I'm totally breaking your game.

Cervator · Sep 5, 2015

Nah you're not breaking it! All volunteer open source just tends to leave some areas getting too little attention and then they get dusty

Then users break things so we know they're broken and can hopefully get somebody to go fix it.

The pastebin thing we saw recently and thought it was a temporary issue with the pastebin upload API. Maybe there's a more substantial problem there, will follow-up.

The game log seems to indicate the same error, yet with the dev version that should be fixed, hmm. Are you at all using part of the old stable build? Mixing together any files or saves can be problematic. You can run the game with a different homedir set (launcher should have options, or you can run with -homedir to force the game to keep data in the same dir you run it from) and see if that makes a difference. If not then we haven't fully fixed it.

I think there are two separate issues. Well, three counting pastebin.

The two weird performance monitor counters. Some real issue is causing them to spike when something is done in-game
#1855 possibly not being fully fixed
Pastebin API with the CrashReporter is broken / out of date. I added a note to https://github.com/MovingBlocks/CrashReporter/issues/16

ronnietucker · Sep 6, 2015

I was using the Jenkins version you recommended several posts above (http://forum.terasology.org/threads/delta-too-great.1374/#post-12371).

My previous save games did appear when I ran this dev version, although I did create a new save for the dev version, so it probably was using the old location. ~~What's the command to use a different location for saves?~~

Will create a new save in a DATA directory away from the previous version.

EDIT: I've no idea where Terasology is saving to. I have /home/Downloads/TerasologyOmega (and /home/Downloads/TerasologyOmega-dev). Inside there is the Linux .sh file that I run to start the game. I've no hidden .terasology folder in /home or in /home/Downloads.

ronnietucker · Sep 6, 2015

Weird. Even with the DEV version, and saving into /home/Downloads/TerasologyOmega-dev/DATA I'm getting a crash when I exit to menu:
https://docs.google.com/uc?id=0B5l2RT_UOCXRbmx1bUJndzlvSzQ&export=download
https://docs.google.com/uc?id=0B5l2RT_UOCXRYTlQd1RyTWlpdm8&export=download
I didn't even build anything. Just got some twigs and sticks.

Some terminal stuff:

Code:

16:28:58.055 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered Block Core:Iris with id 1082
16:28:59.578 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered BlockFamily[Core:Lavender]
16:28:59.587 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered Block Core:Lavender with id 1083
16:28:59.597 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered BlockFamily[Core:RedFlower]
16:28:59.598 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered Block Core:RedFlower with id 1084
16:29:06.924 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1100), capping to 1000
16:29:12.056 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1254), capping to 1000
16:29:15.749 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (2574), capping to 1000
16:29:26.160 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered BlockFamily[Core:YellowFlower]
16:29:26.164 [Chunk-Generator-5] INFO  o.t.w.b.internal.BlockManagerImpl - Registered Block Core:YellowFlower with id 1085
16:29:56.647 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
16:29:56.724 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
16:30:02.670 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1329), capping to 1000
16:30:04.168 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
16:30:05.267 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1182), capping to 1000
16:30:06.883 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1082), capping to 1000
16:30:08.194 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1063), capping to 1000
16:30:10.283 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1448), capping to 1000
16:30:12.011 [main] WARN  o.t.engine.internal.TimeBase - Delta too great (1185), capping to 1000
16:30:56.761 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
16:30:56.765 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
16:30:59.379 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
16:31:56.793 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
16:31:56.806 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
16:31:59.334 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
16:32:34.106 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
16:32:34.111 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
16:32:34.113 [main] INFO  o.t.n.internal.NetworkSystemImpl - Client disconnected: ronnie
16:32:34.116 [main] INFO  o.t.logic.console.ConsoleImpl - [NOTIFICATION] Player "ronnie" has left the game
16:32:34.119 [main] INFO  o.t.n.internal.NetworkSystemImpl - Network shutdown
16:32:36.666 [Saving-0] INFO  o.t.p.internal.SaveTransaction - Save game finished
16:32:36.667 [main] WARN  org.reflections.Reflections - given scan urls are empty. set urls in the configuration
16:32:38.185 [main] ERROR o.terasology.engine.TerasologyEngine - Uncaught exception, attempting clean game shutdown
java.lang.NullPointerException: null
    at org.terasology.rendering.opengl.OpenGLTexture.getData(OpenGLTexture.java:183) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:74) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshFactory.generateIconMeshData(IconMeshFactory.java:62) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.rendering.iconmesh.IconMeshDataProducer.getAssetData(IconMeshDataProducer.java:80) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.assets.AssetType.reloadFromProducers(AssetType.java:467) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.AssetType.refresh(AssetType.java:155) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.assets.module.ModuleAwareAssetTypeManager.switchEnvironment(ModuleAwareAssetTypeManager.java:389) ~[gestalt-asset-core-4.1.2.jar:4.1.2]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.cheapAssetManagerUpdate(EnvironmentSwitchHandler.java:128) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.engine.bootstrap.EnvironmentSwitchHandler.handleSwitchToEmptyEnivronment(EnvironmentSwitchHandler.java:149) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.engine.modes.StateIngame.dispose(StateIngame.java:157) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.engine.TerasologyEngine.switchState(TerasologyEngine.java:498) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.TerasologyEngine.processPendingState(TerasologyEngine.java:491) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.TerasologyEngine.mainLoop(TerasologyEngine.java:396) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.TerasologyEngine.run(TerasologyEngine.java:369) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.Terasology.main(Terasology.java:154) [Terasology.jar:1516, origin/develop, 1516]
16:32:38.186 [main] INFO  o.terasology.engine.TerasologyEngine - Shutting down Terasology...
16:32:38.187 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Creating game snapshot
16:32:38.189 [main] INFO  o.t.p.i.ReadWriteStorageManager - Saving - Snapshot created: Writing phase starts
16:32:38.189 [main] INFO  o.t.n.internal.NetworkSystemImpl - Network shutdown
16:32:38.195 [main] ERROR o.terasology.engine.TerasologyEngine - Clean game shutdown after an uncaught exception failed
java.lang.NullPointerException: null
    at com.bulletphysics.collision.broadphase.Dbvt.removeleaf(Dbvt.java:646) ~[tera-bullet-1.0.3.jar:na]
    at com.bulletphysics.collision.broadphase.Dbvt.remove(Dbvt.java:184) ~[tera-bullet-1.0.3.jar:na]
    at com.bulletphysics.collision.broadphase.DbvtBroadphase.destroyProxy(DbvtBroadphase.java:190) ~[tera-bullet-1.0.3.jar:na]
    at com.bulletphysics.collision.dispatch.CollisionWorld.destroy(CollisionWorld.java:109) ~[tera-bullet-1.0.3.jar:na]
    at org.terasology.physics.bullet.BulletPhysics.dispose(BulletPhysics.java:160) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.engine.modes.StateIngame.dispose(StateIngame.java:147) ~[engine-0.54.2-SNAPSHOT.jar:1516, develop, 1516, pre-alpha]
    at org.terasology.engine.TerasologyEngine.cleanup(TerasologyEngine.java:438) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.TerasologyEngine.run(TerasologyEngine.java:375) ~[engine-0.54.2-SNAPSHOT.jar:1516, origin/develop, 1516]
    at org.terasology.engine.Terasology.main(Terasology.java:154) [Terasology.jar:1516, origin/develop, 1516]

(process:23143): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

(process:23176): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

(process:23184): GLib-CRITICAL **: g_slice_set_config: assertion 'sys_page_size == 0' failed

PS: CrashHandler Pastebin thing still happens too.

Cervator · Sep 6, 2015

Interesting logging there on physics, although that's probably just a consequence from the crash and game shutdown.

As far as the homedir goes, it gets logged so if you crash and the CrashReporter pops up the path should be at the top of one of the logs. Or if you launch via java -jar libs/Terasology.jar in a terminal the logging should appear there and again start out saying what the game determined the homedir to be.

Does the crash on going to menu happen every time now in TTA? Does it happen at all if you just run with Core?

I just tried it with the same dev build and no crash, but then I'm on Win7.

Going to ping @Immortius since he's the expert in the area (and fixed #1855) - might take a bit as he keeps busy

ronnietucker · Sep 6, 2015

The 'return to menu' crash is almost every time. If I choose to 'exit to desktop' it doesn't crash.

No problems at all with the Core gameplay side of things.

New save game and this time I upped the memory limit to 2GB. Again it was gobbled up then your good friends showed up:
(pressed F4 to toggle through the data)

Framerate is normally 15-25 depending on where I am, or what I'm doing (most video effects are on except vignette and grain). As soon as a save kicks in the FPS will drop to about 5 or 8. Bummer.

Yes, my DATA folder has a logs folder with several previous crashes so I've zipped them up and attached them here too. Just for the hell of it.

Cervator · Sep 6, 2015

Oohhh! The lag kicks in after the first auto save? That's an important piece of the puzzle!

Pinging @Florian on that note then, as he's the most familiar with the auto save. And thanks for the extra logs!

May still take a little while, but I appreciate all the extra details, super helpful!

Cervator · Sep 13, 2015

Was able to provoke some issues with the auto-save myself, submitted https://github.com/MovingBlocks/Terasology/issues/1929 for later review/work

Cervator · Sep 21, 2015

Annnd like the Cities bug the crash here may be resolved now. In the latest dev build though, not the release

Try out http://jenkins.terasology.org/job/DistroOmega/lastSuccessfulBuild/artifact/distros/omega/build/distributions/TerasologyOmega.zip and see if it helps.

Archived Delta too great

ronnietucker

New Member

Cervator

Org Co-Founder & Project Lead

ronnietucker

New Member

ronnietucker

New Member

Cervator

Org Co-Founder & Project Lead

ronnietucker

New Member

Cervator

Org Co-Founder & Project Lead

ronnietucker

New Member

ronnietucker

New Member

Cervator

Org Co-Founder & Project Lead

ronnietucker

New Member

ronnietucker

New Member

Cervator

Org Co-Founder & Project Lead

ronnietucker

New Member

Attachments

Cervator

Org Co-Founder & Project Lead

Cervator

Org Co-Founder & Project Lead

Cervator

Org Co-Founder & Project Lead