Weir server crash due to excessive tick
collinticer opened this issue ยท 11 comments
I'm honestly not sure that this issue is due to Exposure or not, but I have noticed it happening since Exposure was installed. Originally the server had 1.8.6 but it now has version 1.8.8. When I check the logs, it seems like only 1 or 2 people are on the server. It could be a legitimate overload of the server, but there's nothing significant about the player load or what they're doing when the crash happens. And each time it crashes, I see mention of Exposure leading up to the lock up so I wanted to create this issue in the off chance there's something more to this and it can hopefully be fixed.
It's version 1.8.8 running on NeoForge.
I've attached the latest crash report, but I'll also provide the snippet I see where it mentions Exposure.
java.lang.Error: ServerHangWatchdog detected that a single server tick took 60000004.00 seconds (should be max 0.05)
at TRANSFORMER/[email protected]/net.minecraft.server.level.ChunkMap$TrackedEntity.updatePlayer(ChunkMap.java:1290) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:lithium.mixins.json:alloc.entity_tracker.ChunkMap$TrackedEntityMixin from mod lithium,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.level.ChunkMap.move(ChunkMap.java:1001) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:ftbchunks-common.mixins.json:ChunkMapMixin from mod ftbchunks,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.level.ServerChunkCache.move(ServerChunkCache.java:463) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:lithium.mixins.json:world.chunk_access.ServerChunkCacheMixin from mod lithium,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.network.ServerGamePacketListenerImpl.handleMovePlayer(ServerGamePacketListenerImpl.java:955) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,re:classloading,pl:mixin:APP:exposure-common.mixins.json:ServerGamePacketListenerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.network.protocol.game.ServerboundMovePlayerPacket.handle(ServerboundMovePlayerPacket.java:142) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:classloading}
at TRANSFORMER/[email protected]/net.minecraft.network.protocol.game.ServerboundMovePlayerPacket$Pos.handle(ServerboundMovePlayerPacket.java:50) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:classloading}
at TRANSFORMER/[email protected]/net.minecraft.network.protocol.PacketUtils.lambda$ensureRunningOnSameThread$0(PacketUtils.java:27) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:classloading,re:mixin}
at TRANSFORMER/[email protected]/net.minecraft.network.protocol.PacketUtils$$Lambda/0x0000023112b15200.run(Unknown Source) ~[?:?] {}
at TRANSFORMER/[email protected]/net.minecraft.server.TickTask.run(TickTask.java:18) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:classloading}
at TRANSFORMER/[email protected]/net.minecraft.util.thread.BlockableEventLoop.doRunTask(BlockableEventLoop.java:148) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:computing_frames,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B}
at TRANSFORMER/[email protected]/net.minecraft.util.thread.ReentrantBlockableEventLoop.doRunTask(ReentrantBlockableEventLoop.java:23) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,re:computing_frames,re:classloading}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.doRunTask(MinecraftServer.java:872) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.doRunTask(MinecraftServer.java:170) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.util.thread.BlockableEventLoop.pollTask(BlockableEventLoop.java:122) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:computing_frames,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.pollTaskInternal(MinecraftServer.java:855) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.pollTask(MinecraftServer.java:849) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.util.thread.BlockableEventLoop.runAllTasks(BlockableEventLoop.java:111) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:computing_frames,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.waitUntilNextTick(MinecraftServer.java:825) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.runServer(MinecraftServer.java:712) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer.lambda$spin$2(MinecraftServer.java:267) ~[server-1.21.1-20240808.144430-srg.jar%23137!/:?] {re:mixin,pl:accesstransformer:B,re:classloading,pl:accesstransformer:B,pl:mixin:APP:exposure-common.mixins.json:MinecraftServerMixin from mod exposure,pl:mixin:A}
at TRANSFORMER/[email protected]/net.minecraft.server.MinecraftServer$$Lambda/0x00000231127c9ae0.run(Unknown Source) ~[?:?] {}
at java.base/java.lang.Thread.runWith(Thread.java:1596) [?:?] {re:mixin}
at java.base/java.lang.Thread.run(Thread.java:1583) [?:?] {re:mixin}
Maybe I'm wrong, but I think it's more to to with Lithium than Exposure.
My mixin (that's mentioned in the log) is targeting handleRenameItem, which is for item renaming and it shouldn't cause any lag or hangs.
There is one place where Exposure was causing some server lag - and I have scaled it back since, so it shouldn't be a problem. And if players on your server have not been taking images right before the crash - it too shouldn't have any effect on the lag.
That's fair, and I'm not sure. I will say that I added Lithium in hopes to fix this after the first crash back with the 1.8.6 version installed about a week ago. That crash was essentially the same error but without the mention of Lithium. I'll include that crash as well, but I understand it's an entirely different Exposure version, Lithium wasn't installed, etc, so it may not yield much.
crash-2025-03-03_23.46.54-server.txt
And I don't believe anyone else on the server has really used the mod besides myself so far - so I don't think anyone was taking pictures or anything prior to the crash.
@collinticer I have released 1.8.9 version with small change to my MinecraftServer mixin and added config option to disable cleanup of timed out upload data.
That's all that I could come up with right now.
Best way would be to test without turning off cleanup in config first, that way we would know what fixes it (if it fixes it at all).
Ok that makes sense. I will install this and give it some time to see if the crash happens again. It usually happens within a day. I will post back here with my results.
I appreciate you looking into this.
While downloading that new release, I noticed something and I don't know how i missed this prior.. but I am completely missing this dependency. Would that cause the issue? https://modrinth.com/mod/forge-config-api-port
To start, I'm continuing with the 1.8.8 version, but i added the Forge Config API port mod. I'll monitor and if the issue persists, I will try the 1.8.9 release with the config option you suggest and test again.
Forge Config Api Port is required dependency for Exposure, the mod shouldn't run without it. I thought it's not possible to run the game with it missing. Strange.
That's correct I'm on NeoForge. I wasn't sure if it was actually required or not. That API made it seem like it possibly was still for NeoForge, specifically the bit about "Forge configs on Fabric & NeoForge".
Allows developers to use NeoForge configs on Fabric & Forge and Forge configs on Fabric & NeoForge.
And it does include releases for NeoForge as well.
What's odd is that so far the server has been stable with 1.8.8 of Exposure and with the Forge Config API Port added and it would normally lock up sometime by now.
As I understand it, you would need to install it on NeoForge if I had used Forge config as main one.
Since Forge/NeoForge split, their configs are probably have some differences right now. So basically as a modder, you choose what config system you're using, and config api port is something like a compatibility layer between the two.
Exposure uses NeoForge config system natively, so you need it on fabric only.
If it is indeed Forge Config API Port that's causing the issue, then that's pretty weird.