[SOLVED]1.7.10 crash on player specific login (with logs!)

[UberAffe]

I have a custom modpack that a few people have started playing on.

One of the players got kicked with an internal server error while going down a ladder and a villager walked beneath them.

 

Things I tried:

having them restart their client,

restarting both server and client,

editing that players .dat file to put them to known safe coordinates

 

This is what happens on the server when they try to log in:

 

[19:42:49] [server thread/ERROR] [FML/]: Index: 12 Listeners:
[19:42:49] [server thread/ERROR] [FML/]: 0: NORMAL
[19:42:49] [server thread/ERROR] [FML/]: 1: ASM: com.darkona.adventurebackpack.handlers.PlayerEventHandler@34877aaf tickPlayer(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 2: ASM: tconstruct.armor.ArmorAbilities@53dbd223 playerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 3: ASM: crazypants.enderio.item.MagnetController@59ed79de onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 4: ASM: crazypants.enderio.item.darksteel.DarkSteelController@55f92efa onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 5: ASM: com.rwtema.extrautils.crafting.LawSwordCraftHandler@3ad13c37 event(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 6: ASM: chylex.hee.mechanics.compendium.events.CompendiumEvents@7f68b22b onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 7: ASM: com.emoniph.witchery.common.ServerTickEvents@7820040e onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 8: ASM: codechicken.nei.ServerHandler@7b461ac6 tickEvent(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 9: ASM: extracells.util.ExtraCellsEventHandler@25312228 onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 10: ASM: openblocks.common.ServerTickHandler@2a3a628d onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 11: ASM: tombenpotter.sanguimancy.util.EventHandler@2920e376 onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 12: ASM: code.elix_x.mods.teleplates.events.OnPlayerTickEvent@421c5861 tick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 13: ASM: thaumic.tinkerer.common.potion.PotionEffectHandler@26cf3ef onPlayerTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 14: ASM: com.rwtema.extrautils.worldgen.Underdark.DarknessTickHandler@248d3463 tickStart(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 15: ASM: vazkii.botania.common.block.BlockPistonRelay@b60ed88 tickEnd(Lcpw/mods/fml/common/gameevent/TickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 16: ASM: lumien.randomthings.Handler.RTTickHandler@287ff2de tick(Lcpw/mods/fml/common/gameevent/TickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 17: ASM: appeng.hooks.TickHandler@5ad2b17b onTick(Lcpw/mods/fml/common/gameevent/TickEvent;)V
[19:42:49] [server thread/ERROR] [FML/]: 18: LOWEST
[19:42:49] [server thread/ERROR] [FML/]: 19: ASM: vazkii.botania.common.item.ItemRegenIvy@2ccadab9 onTick(Lcpw/mods/fml/common/gameevent/TickEvent$PlayerTickEvent;)V
[19:42:49] [server thread/DEBUG] [NotEnoughItems/]: Unloading Player: EmeraldOtter

 

 

the last line is what happens when I shut down the server, not when they get kicked.

 

Their client doesn't actually crash but this is their fml latest log:

 

[18:32:06] [Netty Client IO #0/INFO] [FML/]: Attempting connection with missing mods [inventorytweaks] at SERVER
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Server FML protocol version 2, 4 byte dimension received 0
[18:32:10] [Netty Client IO #1/TRACE] [FML/]: $ServerHello->FMLHandshakeClientState$2:HELLO
[18:32:10] [Netty Client IO #1/INFO] [FML/]: Server protocol version 2
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Received override dimension 0
[18:32:10] [Netty Client IO #1/TRACE] [FML/]: $ModList:101 mods->FMLHandshakeClientState$3:WAITINGSERVERDATA
[18:32:10] [Netty Client IO #1/INFO] [FML/]: Attempting connection with missing mods [inventorytweaks] at SERVER
[18:32:10] [Netty Client IO #1/TRACE] [FML/]: $ModIdData:4371 mappings->FMLHandshakeClientState$4:WAITINGSERVERCOMPLETE
[18:32:10] [Netty Client IO #1/INFO] [FML/]: Injecting existing block and item data into this client instance
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Registry consistency check successful
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Fixed block id mismatch witchery:embermoss: 1394 (init) -> 1393 (map).
... fixing id's
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Fixed item id mismatch witchery:stairswoodrowan: 1406 (init) -> 1405 (map).
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Registry consistency check successful
[18:32:10] [Netty Client IO #1/TRACE] [mcp/mcp]: Sending event FMLModIdMappingEvent to mod mcp
...sending events
[18:32:10] [Netty Client IO #1/TRACE] [aobd/aobd]: Sent event FMLModIdMappingEvent to mod aobd
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Bar Step: ModIdMapping - Another One Bites The Dust took 0.000s
[18:32:10] [Netty Client IO #1/DEBUG] [FML/]: Bar Finished: ModIdMapping took 0.027s
[18:32:10] [Netty Client IO #1/INFO] [FML/]: Applying holder lookups
[18:32:10] [Netty Client IO #1/INFO] [FML/]: Holder lookups applied
[18:32:10] [Netty Client IO #1/TRACE] [FML/]: $HandshakeAck:{2}->FMLHandshakeClientState$5:PENDINGCOMPLETE
[18:32:10] [Netty Client IO #1/TRACE] [FML/]: $HandshakeAck:{3}->FMLHandshakeClientState$6:COMPLETE
[18:32:10] [Client thread/DEBUG] [FML/]: The fluid thaumicenergistics:gaseouscognitioessentia has been selected as the default fluid for gaseouscognitioessentia
... default fluids
[18:32:10] [Client thread/DEBUG] [FML/]: The fluid thaumicenergistics:gaseouscorpusessentia has been selected as the default fluid for gaseouscorpusessentia
[18:32:10] [Client thread/DEBUG] [OpenMods/]: openmods.network.IdSyncManager.decodeIds(IdSyncManager.java:113): Received data store for key rpc_methods, packet size = 337
[18:32:10] [Client thread/DEBUG] [OpenMods/]: openmods.network.IdSyncManager.decodeIds(IdSyncManager.java:113): Received data store for key events, packet size = 167
[18:32:10] [Client thread/DEBUG] [OpenMods/]: openmods.network.IdSyncManager.decodeIds(IdSyncManager.java:113): Received data store for key rpc_targets, packet size = 107
[18:32:10] [Client thread/INFO] [FML/]: [Client thread] Client side modded connection established
[18:32:10] [Client thread/INFO] [NEIAddons/]: Connected to new server, resetting commands
[18:32:10] [Client thread/DEBUG] [FML/]: Overriding dimension: using 0
[18:32:11] [Client thread/INFO] [journeymap/]: Requesting World ID
[18:32:12] [Client thread/INFO] [ThermalExpansion/]: Receiving Server Configuration...
[18:32:12] [Client thread/INFO] [THAUMCRAFT/]: Client received server config settings.
[18:32:12] [Client thread/INFO] [THAUMCRAFT/]: CHEAT_SHEET[false], WARDED_STONE[true], MIRRORS[true], HARD_NODES[true], WUSS_MODE[false], RESEARCH_DIFFICULTY[0], ASPECT_TOTAL_CAP[100
[18:32:12] [Client thread/INFO] [malisiscore/]: Found hooks for net.minecraft.network.NetHandlerPlayServer (nh)
[18:32:12] [Netty Client IO #1/INFO] [Armory/]: Started loading custom ArmorMaterial Values from Config.
[18:32:12] [Client thread/DEBUG] [NotEnoughItems/]: Loading Remote World
[18:32:12] [Netty Client IO #1/INFO] [Armory/]: Loading of the custom ArmorMaterial Values has succesfully been performed!
[18:32:12] [Netty Client IO #1/INFO] [THAUMCRAFT/]: Restoring client configs.
[18:32:12] [Netty Client IO #1/DEBUG] [OpenMods/]: openmods.network.IdSyncManager.onDisconnect(IdSyncManager.java:124): Disconnected, restoring local data
[18:32:12] [Client thread/ERROR] [NotEnoughItems/]: Failed to find identifier for: com.kentington.thaumichorizons.common.items.ItemGolemBellTH@b019f7b
[18:32:13] [Client thread/DEBUG] [CCL ASM/]: Inserted super call into [bfo.e()V]
[18:32:13] [Client thread/DEBUG] [CCL ASM/]: Injecting before method [bfl.a(Labt;II)Z]
[18:32:13] [Client thread/DEBUG] [CCL ASM/]: Injecting before method [bfl.b(Labt;II)Z]
[18:32:13] [Client thread/DEBUG] [FML/]: Found a minecraft related file at C:\Users\compName\Documents\Curse\Minecraft\Install\libraries\net\minecraftforge\forge\1.7.10-10.13.4.1492-1.7.10\forge-1.7.10-10.13.4.1492-1.7.10.jar, examining for codechicken classes
... found related files
[18:32:15] [Client thread/DEBUG] [FML/]: Found a minecraft related file at C:\Users\compName\Documents\Curse\Minecraft\Install\versions\forge-10.13.4.1492\forge-10.13.4.1492.jar, examining for codechicken classes
[18:32:15] [Client thread/ERROR] [CodeChickenCore/]: Failed to scan C:\Users\compName\Documents\Curse\Minecraft\Install\versions\forge-10.13.4.1492\forge-10.13.4.1492.jar, the zip file is invalid
java.util.zip.ZipException: invalid entry size (expected 67324752 but got 2231 bytes)
at java.util.zip.ZipInputStream.readEnd(ZipInputStream.java:384) ~[?:1.8.0_25]
at java.util.zip.ZipInputStream.read(ZipInputStream.java:196) ~[?:1.8.0_25]
at java.util.zip.ZipInputStream.closeEntry(ZipInputStream.java:140) ~[?:1.8.0_25]
at java.util.zip.ZipInputStream.getNextEntry(ZipInputStream.java:118) ~[?:1.8.0_25]
at codechicken.core.ClassDiscoverer.readFromZipFile(ClassDiscoverer.java:116) ~[ClassDiscoverer.class:?]
at codechicken.core.ClassDiscoverer.findClasspathMods(ClassDiscoverer.java:100) [ClassDiscoverer.class:?]
at codechicken.core.ClassDiscoverer.findClasses(ClassDiscoverer.java:51) [ClassDiscoverer.class:?]
at codechicken.nei.NEIClientConfig.bootNEI(NEIClientConfig.java:273) [NEIClientConfig.class:?]
at codechicken.nei.NEIClientConfig.loadWorld(NEIClientConfig.java:208) [NEIClientConfig.class:?]
at codechicken.nei.NEICPH.handleSMPCheck(NEICPH.java:109) [NEICPH.class:?]
at codechicken.nei.NEICPH.handlePacket(NEICPH.java:23) [NEICPH.class:?]
at codechicken.lib.packet.PacketCustom$ClientInboundHandler.handle(PacketCustom.java:100) [PacketCustom$ClientInboundHandler.class:?]
at codechicken.lib.packet.PacketCustom$CustomInboundHandler.channelRead0(PacketCustom.java:77) [PacketCustom$CustomInboundHandler.class:?]
at codechicken.lib.packet.PacketCustom$CustomInboundHandler.channelRead0(PacketCustom.java:64) [PacketCustom$CustomInboundHandler.class:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:98) [simpleChannelInboundHandler.class:?]
at io.netty.channel.DefaultChannelHandlerContext.invokeChannelRead(DefaultChannelHandlerContext.java:337) [DefaultChannelHandlerContext.class:?]
at io.netty.channel.DefaultChannelHandlerContext.fireChannelRead(DefaultChannelHandlerContext.java:323) [DefaultChannelHandlerContext.class:?]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:785) [DefaultChannelPipeline.class:?]
at io.netty.channel.embedded.EmbeddedChannel.writeInbound(EmbeddedChannel.java:169) [EmbeddedChannel.class:?]
at cpw.mods.fml.common.network.internal.FMLProxyPacket.func_148833_a(FMLProxyPacket.java:77) [FMLProxyPacket.class:?]
at net.minecraft.network.NetworkManager.func_74428_b(NetworkManager.java:212) [ej.class:?]
at net.minecraft.client.multiplayer.GuiConnecting.func_73876_c(SourceFile:78) [bcx.class:?]
at net.minecraft.client.Minecraft.func_71407_l(Minecraft.java:1661) [bao.class:?]
at net.minecraft.client.Minecraft.func_71411_J(Minecraft.java:973) [bao.class:?]
at net.minecraft.client.Minecraft.func_99999_d(Minecraft.java:898) [bao.class:?]
at net.minecraft.client.main.Main.main(SourceFile:148) [Main.class:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_25]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_25]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_25]
at java.lang.reflect.Method.invoke(Method.java:483) ~[?:1.8.0_25]
at net.minecraft.launchwrapper.Launch.launch(Launch.java:135) [launchwrapper-1.12.jar:?]
at net.minecraft.launchwrapper.Launch.main(Launch.java:28) [launchwrapper-1.12.jar:?]
[18:32:15] [Client thread/DEBUG] [FML/]: Found a minecraft related file at C:\Users\compName\Documents\Curse\Minecraft\Instances\Moar fun\mods\1.7.10 Technomancy  - 0.11.2 - 1.7.10.jar, examining for codechicken classes
... found related files
[18:32:18] [Client thread/DEBUG] [FML/]: Found a minecraft related file at C:\Users\compName\Documents\Curse\Minecraft\Instances\Moar fun\mods\witchery-1.7.10-0.24.1.jar, examining for codechicken classes
[18:32:19] [Client thread/DEBUG] [FML/]: Found a minecraft related file at C:\Users\compName\Documents\Curse\Minecraft\Instances\Moar fun\mods\AOBD-2.8.1.jar, examining for codechicken classes
[18:32:19] [Client thread/DEBUG] [NotEnoughItems/]: Loaded cofh.plugins.nei.NEICoFHConfig
...loaded
[18:32:19] [Client thread/DEBUG] [NotEnoughItems/]: Loaded com.emoniph.witchery.integration.NEIWitcheryConfig
[18:32:19] [Client thread/INFO] [Waila/]: Received server authentication msg. Remote sync will be activated
[18:32:19] [Client thread/INFO] [NEIAddons/]: Received handshake from server
[18:32:19] [Client thread/INFO] [NEIAddons/]: Available server commands: SetAE2FakeSlot
[18:32:19] [Client thread/INFO] [FML/]: Ars Magica 2 >> Received player login packet.
[18:32:19] [Client thread/INFO] [FML/]: Secondary tree cap: 99
[18:32:19] [Client thread/INFO] [FML/]: Disabled skills: 0
[18:32:19] [Client thread/INFO] [FML/]: Mana cap: 0.00
[18:32:19] [Client thread/WARN] [FML/]: =============================================================
[18:32:19] [Client thread/WARN] [FML/]: MOD HAS DIRECT REFERENCE System.exit() THIS IS NOT ALLOWED REROUTING TO FML!
[18:32:19] [Client thread/WARN] [FML/]: Offendor: truetyper/TrueTypeFont.loadImage(Ljava/awt/image/BufferedImage;)I
[18:32:19] [Client thread/WARN] [FML/]: Use FMLCommonHandler.exitJava instead
[18:32:19] [Client thread/WARN] [FML/]: =============================================================
[18:32:20] [Client thread/INFO] [sTDOUT/]: [truetyper.TrueTypeFont:<init>:94]: TrueTypeFont loaded: java.awt.Font[family=Arial,name=Arial,style=plain,size=12] - AntiAlias = true
[18:32:20] [Client thread/INFO] [FML/]: Ars Magica 2 >> Compendium unlock state not found to load.  Assuming it hasn't been created yet.
[18:32:20] [Client thread/DEBUG] [FML/]: Reverting to frozen data state.
[18:32:20] [Client thread/INFO] [FML/]: Applying holder lookups
[18:32:20] [Client thread/INFO] [FML/]: Holder lookups applied

 

All the parts that I omitted don't list any errors and they can load in single player fine I expect that it is just the last few lines that will be important.

 

Edit: let the confusion continue ...

I removed the players uuid.dat file along with all the other player specific files in that folder

and they still get the same issue when trying to connect.

 

Other players can connect and play just fine, we have no items that are linked to players either.

 

Edit2:

Began happening to another player when they tried opening a vanilla furnace gui, didn't happen with crafting table gui though.

[LexManos]

Post the actual full log file from the server.

We dont care about the client if it says Internal Server Error.

And the log you posted doesn't have any useful information.

[UberAffe]

I got a fresh log

server started player,

player tried to connect

server stopped

 

Full fml-server-latest:

https://raw.githubusercontent.com/UberAffe/excessivly-large-files/master/technexx-full-fml-log.log

 

10,500 lines is way to much for this, a surprisingly simple number.

[UberAffe]

Found it, sort of.

Removing Teleplates and its dependency xcore fixed the problem, it is a new mod and seems to be causing a problem with its player tick event.

 

Reporting this to mod author.

 

Thanks for taking a look at this anyways LexManos!

[Elix_x]

This should not happen at all... Or mappings are different or what?

This is line that gives NoSuchMethodError:

if(player.rotationPitch == 90 && ((block == TeleplatesBase.teleplate && EnergyManager.canTeleportFromTeleplate(player)) || (player.getItemInUse() != null && player.getItemInUse().getItem() == TeleplatesBase.portableTeleplate && EnergyManager.canTeleportFromPortableTeleplate(player)))){

As you can see, nothing wrong here and

EntityPlayer.func_71011_bu()Lnet/minecraft/item/ItemStack;

method can only be

player.getItemInUse()

.

So i'm confused. It seems that something else caused this error. Compiler? Different forge versions? MCP mappings? 3rd party coremod? What?

 

EDIT: if somebody is interested, this issue on modder support page with some more detailed description: http://www.minecraftforge.net/forum/index.php/topic,33194