Minecraft 1.4.7 and Forge 6.6.2.534: 'root' took aprox X ms

[Longwing]

Hello, I'm trying to track down an odd error with Forge 6.6.2.534 on Minecraft 1.4.7. I'm aware that the most recent version of forge is 7.7.0.594. However, I'm using the last version available for 1.4.7, which is the version of minecraft that most of the mods I run currently support.

 

Put simply, Minecraft appears to "lag" about once a minute. My frame-rate remains perfectly stable, but all entities in the world freeze, and all changes I make become delayed. After about 5 seconds, the world "catches up", either resetting the changes I make, or applying them all in rapid succession. This issue does not evidence itself on unmodified minecraft installs. Nor does it show up when I install mods that don't depend on forge (I have an independent near-vanilla install with Better Sprinting, Optifine, and SEUS shaders installed, and it runs entirely without issue).

 

I created a test instance using MultiMC to try to narrow down the problem. The log included below is from a brand new instance with only forge installed, no additional mods.

 

System:

 

Intel i5-2500K

8gb ram

GeForce GTX 560 Ti

 

 

Software:

 

Win 7 64

Java 1.7.0_17 (AKA Java 7 X64)

MultiMC 4.3.9.71

Minecraft 1.4.7

Forge 6.6.2.534

 

 

ForgeModLoader-client-0.log from test install:

 

2013-03-18 12:19:09 [iNFO] [ForgeModLoader] Forge Mod Loader version 4.7.35.556 for Minecraft 1.4.7 loading

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] All core mods are successfully located

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Discovering coremods

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Found library file argo-2.25.jar present and correct in lib dir

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Found library file guava-12.0.1.jar present and correct in lib dir

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Found library file asm-all-4.0.jar present and correct in lib dir

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Found library file bcprov-jdk15on-147.jar present and correct in lib dir

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Running coremod plugins

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Running coremod plugin FMLCorePlugin

2013-03-18 12:19:09 [iNFO] [ForgeModLoader] Found valid fingerprint for Minecraft Forge. Certificate fingerprint de4cf8a3f3bc15635810044c39240bf96804ea7d

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Coremod plugin FMLCorePlugin run successfully

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Running coremod plugin FMLForgePlugin

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Coremod plugin FMLForgePlugin run successfully

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Validating minecraft

2013-03-18 12:19:09 [FINEST] [ForgeModLoader] Minecraft validated, launching...

2013-03-18 12:19:10 [iNFO] [sTDOUT] 27 achievements

2013-03-18 12:19:10 [iNFO] [sTDOUT] 210 recipes

2013-03-18 12:19:11 [iNFO] [sTDOUT] Setting user: longwing, #################

2013-03-18 12:19:11 [iNFO] [sTDOUT] LWJGL Version: 2.4.2

2013-03-18 12:19:11 [iNFO] [MinecraftForge] Attempting early MinecraftForge initialization

2013-03-18 12:19:11 [iNFO] [sTDOUT] MinecraftForge v6.6.2.534 Initialized

2013-03-18 12:19:11 [iNFO] [ForgeModLoader] MinecraftForge v6.6.2.534 Initialized

2013-03-18 12:19:11 [iNFO] [sTDOUT] Replaced 84 ore recipies

2013-03-18 12:19:11 [iNFO] [MinecraftForge] Completed early MinecraftForge initialization

2013-03-18 12:19:11 [iNFO] [ForgeModLoader] Reading custom logging properties from E:\MultiMC\instances\Forge Testing Install\minecraft\config\logging.properties

2013-03-18 12:19:11 [OFF] [ForgeModLoader] Logging level for ForgeModLoader logging is set to ALL

2013-03-18 12:19:11 [FINE] [ForgeModLoader] Building injected Mod Containers [cpw.mods.fml.common.FMLDummyContainer, net.minecraftforge.common.ForgeDummyContainer]

2013-03-18 12:19:11 [FINE] [ForgeModLoader] Attempting to load mods contained in the minecraft jar file and associated classes

2013-03-18 12:19:11 [FINE] [ForgeModLoader] Found a minecraft related file at E:\MultiMC\instances\Forge Testing Install\minecraft\bin\minecraft.jar, examining for mod candidates

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\bin\lwjgl.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\bin\lwjgl_util.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\bin\jinput.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\lib\argo-2.25.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\lib\guava-12.0.1.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\lib\asm-all-4.0.jar

2013-03-18 12:19:11 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing Install\minecraft\lib\bcprov-jdk15on-147.jar

2013-03-18 12:19:11 [FINE] [ForgeModLoader] Minecraft jar mods loaded successfully

2013-03-18 12:19:11 [iNFO] [ForgeModLoader] Searching E:\MultiMC\instances\Forge Testing Install\minecraft\mods for mods

2013-03-18 12:19:11 [FINE] [ForgeModLoader] Examining file minecraft.jar for potential mods

2013-03-18 12:19:11 [FINE] [ForgeModLoader] The mod container minecraft.jar appears to be missing an mcmod.info file

2013-03-18 12:19:12 [iNFO] [ForgeModLoader] Forge Mod Loader has identified 3 mods to load

2013-03-18 12:19:12 [FINER] [ForgeModLoader] Received a system property request ''

2013-03-18 12:19:12 [FINER] [ForgeModLoader] System property request managing the state of 0 mods

2013-03-18 12:19:12 [FINE] [ForgeModLoader] After merging, found state information for 0 mods

2013-03-18 12:19:12 [FINE] [ForgeModLoader] Reloading logging properties from E:\MultiMC\instances\Forge Testing Install\minecraft\config\logging.properties

2013-03-18 12:19:12 [FINE] [ForgeModLoader] Reloaded logging properties

2013-03-18 12:19:12 [FINE] [mcp] Mod Logging channel mcp configured at default level.

2013-03-18 12:19:12 [iNFO] [mcp] Activating mod mcp

2013-03-18 12:19:12 [FINE] [FML] Mod Logging channel FML configured at default level.

2013-03-18 12:19:12 [iNFO] [FML] Activating mod FML

2013-03-18 12:19:12 [FINE] [Forge] Mod Logging channel Forge configured at default level.

2013-03-18 12:19:12 [iNFO] [Forge] Activating mod Forge

2013-03-18 12:19:12 [FINER] [ForgeModLoader] Verifying mod requirements are satisfied

2013-03-18 12:19:12 [FINER] [ForgeModLoader] All mod requirements are satisfied

2013-03-18 12:19:12 [FINER] [ForgeModLoader] Sorting mods into an ordered list

2013-03-18 12:19:12 [FINER] [ForgeModLoader] Mod sorting completed successfully

2013-03-18 12:19:12 [FINE] [ForgeModLoader] Mod sorting data

2013-03-18 12:19:12 [FINE] [ForgeModLoader] No user mods found to sort

2013-03-18 12:19:12 [FINEST] [mcp] Sending event FMLConstructionEvent to mod mcp

2013-03-18 12:19:12 [FINEST] [mcp] Sent event FMLConstructionEvent to mod mcp

2013-03-18 12:19:12 [FINEST] [FML] Sending event FMLConstructionEvent to mod FML

2013-03-18 12:19:12 [FINEST] [FML] Sent event FMLConstructionEvent to mod FML

2013-03-18 12:19:12 [FINEST] [Forge] Sending event FMLConstructionEvent to mod Forge

2013-03-18 12:19:12 [FINEST] [Forge] Sent event FMLConstructionEvent to mod Forge

2013-03-18 12:19:12 [FINE] [ForgeModLoader] Mod signature data

2013-03-18 12:19:12 [FINE] [ForgeModLoader] mcp(Minecraft Coder Pack:7.26a): minecraft.jar (NO VALID CERTIFICATE FOUND)

2013-03-18 12:19:12 [FINE] [ForgeModLoader] FML(Forge Mod Loader:4.7.35.556): coremods (de4cf8a3f3bc15635810044c39240bf96804ea7d)

2013-03-18 12:19:12 [FINE] [ForgeModLoader] Forge(Minecraft Forge:6.6.2.534): coremods (NO VALID CERTIFICATE FOUND)

2013-03-18 12:19:12 [FINEST] [mcp] Sending event FMLPreInitializationEvent to mod mcp

2013-03-18 12:19:12 [FINEST] [mcp] Sent event FMLPreInitializationEvent to mod mcp

2013-03-18 12:19:12 [FINEST] [FML] Sending event FMLPreInitializationEvent to mod FML

2013-03-18 12:19:12 [FINEST] [FML] Sent event FMLPreInitializationEvent to mod FML

2013-03-18 12:19:12 [FINEST] [Forge] Sending event FMLPreInitializationEvent to mod Forge

2013-03-18 12:19:12 [iNFO] [ForgeModLoader] Configured a dormant chunk cache size of 0

2013-03-18 12:19:12 [FINEST] [Forge] Sent event FMLPreInitializationEvent to mod Forge

2013-03-18 12:19:12 [iNFO] [sTDOUT] Starting up SoundSystem...

2013-03-18 12:19:12 [iNFO] [sTDOUT] Initializing LWJGL OpenAL

2013-03-18 12:19:12 [iNFO] [sTDOUT]    (The LWJGL binding of OpenAL.  For more information, see http://www.lwjgl.org)

2013-03-18 12:19:12 [iNFO] [sTDOUT] OpenAL initialized.

2013-03-18 12:19:13 [FINEST] [mcp] Sending event FMLInitializationEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [mcp] Sent event FMLInitializationEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [FML] Sending event FMLInitializationEvent to mod FML

2013-03-18 12:19:13 [FINEST] [FML] Sent event FMLInitializationEvent to mod FML

2013-03-18 12:19:13 [FINEST] [Forge] Sending event FMLInitializationEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [Forge] Sent event FMLInitializationEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod mcp

2013-03-18 12:19:13 [FINEST] [mcp] Sending event IMCEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [mcp] Sent event IMCEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod FML

2013-03-18 12:19:13 [FINEST] [FML] Sending event IMCEvent to mod FML

2013-03-18 12:19:13 [FINEST] [FML] Sent event IMCEvent to mod FML

2013-03-18 12:19:13 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod Forge

2013-03-18 12:19:13 [FINEST] [Forge] Sending event IMCEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [Forge] Sent event IMCEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [mcp] Sending event FMLPostInitializationEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [mcp] Sent event FMLPostInitializationEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [FML] Sending event FMLPostInitializationEvent to mod FML

2013-03-18 12:19:13 [FINEST] [FML] Sent event FMLPostInitializationEvent to mod FML

2013-03-18 12:19:13 [FINEST] [Forge] Sending event FMLPostInitializationEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [Forge] Sent event FMLPostInitializationEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [mcp] Sending event FMLLoadCompleteEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [mcp] Sent event FMLLoadCompleteEvent to mod mcp

2013-03-18 12:19:13 [FINEST] [FML] Sending event FMLLoadCompleteEvent to mod FML

2013-03-18 12:19:13 [FINEST] [FML] Sent event FMLLoadCompleteEvent to mod FML

2013-03-18 12:19:13 [FINEST] [Forge] Sending event FMLLoadCompleteEvent to mod Forge

2013-03-18 12:19:13 [FINEST] [Forge] Sent event FMLLoadCompleteEvent to mod Forge

2013-03-18 12:19:13 [iNFO] [ForgeModLoader] Forge Mod Loader has successfully loaded 3 mods

2013-03-18 12:19:13 [iNFO] [sTDERR] java.io.IOException: Server returned HTTP response code: 503 for URL: http://s3.amazonaws.com/MinecraftResources/

2013-03-18 12:19:13 [iNFO] [sTDERR] at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source)

2013-03-18 12:19:13 [iNFO] [sTDERR] at arn.run(ThreadDownloadResources.java:57)

2013-03-18 12:19:17 [FINEST] [mcp] Sending event FMLServerAboutToStartEvent to mod mcp

2013-03-18 12:19:17 [FINEST] [mcp] Sent event FMLServerAboutToStartEvent to mod mcp

2013-03-18 12:19:17 [FINEST] [FML] Sending event FMLServerAboutToStartEvent to mod FML

2013-03-18 12:19:17 [FINEST] [FML] Sent event FMLServerAboutToStartEvent to mod FML

2013-03-18 12:19:17 [FINEST] [Forge] Sending event FMLServerAboutToStartEvent to mod Forge

2013-03-18 12:19:17 [FINEST] [Forge] Sent event FMLServerAboutToStartEvent to mod Forge

2013-03-18 12:19:17 [FINE] [fml.ItemTracker] The difference set is equal

2013-03-18 12:19:17 [iNFO] [ForgeModLoader] Loading dimension 0 (New World) (bdz@6b4e4e37)

2013-03-18 12:19:17 [iNFO] [ForgeModLoader] Loading dimension 1 (New World) (bdz@6b4e4e37)

2013-03-18 12:19:17 [iNFO] [ForgeModLoader] Loading dimension -1 (New World) (bdz@6b4e4e37)

2013-03-18 12:19:18 [FINEST] [mcp] Sending event FMLServerStartingEvent to mod mcp

2013-03-18 12:19:18 [FINEST] [mcp] Sent event FMLServerStartingEvent to mod mcp

2013-03-18 12:19:18 [FINEST] [FML] Sending event FMLServerStartingEvent to mod FML

2013-03-18 12:19:18 [FINEST] [FML] Sent event FMLServerStartingEvent to mod FML

2013-03-18 12:19:18 [FINEST] [Forge] Sending event FMLServerStartingEvent to mod Forge

2013-03-18 12:19:18 [FINEST] [Forge] Sent event FMLServerStartingEvent to mod Forge

2013-03-18 12:19:18 [FINEST] [mcp] Sending event FMLServerStartedEvent to mod mcp

2013-03-18 12:19:18 [FINEST] [mcp] Sent event FMLServerStartedEvent to mod mcp

2013-03-18 12:19:18 [FINEST] [FML] Sending event FMLServerStartedEvent to mod FML

2013-03-18 12:19:18 [FINEST] [FML] Sent event FMLServerStartedEvent to mod FML

2013-03-18 12:19:18 [FINEST] [Forge] Sending event FMLServerStartedEvent to mod Forge

2013-03-18 12:19:18 [FINEST] [Forge] Sent event FMLServerStartedEvent to mod Forge

2013-03-18 12:21:11 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 350.569293 ms

2013-03-18 12:21:13 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 244.531457 ms

2013-03-18 12:21:18 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 250.957116 ms

2013-03-18 12:21:43 [iNFO] [sTDOUT] Started on 192.168.1.101:54650

2013-03-18 12:21:43 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 108.527737 ms

2013-03-18 12:21:53 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 178.364366 ms

2013-03-18 12:21:54 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 179.796541 ms

2013-03-18 12:22:22 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 182.355484 ms

2013-03-18 12:22:25 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 248.069752 ms

2013-03-18 12:23:37 [iNFO] [sTDOUT] Something's taking too long! 'root.gameRenderer.gui.debug' took aprox 219.918579 ms

2013-03-18 12:23:37 [iNFO] [sTDOUT] Something's taking too long! 'root.gameRenderer.gui' took aprox 220.249177 ms

2013-03-18 12:23:37 [iNFO] [sTDOUT] Something's taking too long! 'root.gameRenderer' took aprox 232.80193 ms

2013-03-18 12:23:37 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 233.978149 ms

2013-03-18 12:27:54 [iNFO] [sTDOUT] Something's taking too long! 'root.gameRenderer.level' took aprox 102.273131 ms

2013-03-18 12:27:54 [iNFO] [sTDOUT] Something's taking too long! 'root.gameRenderer' took aprox 102.989684 ms

2013-03-18 12:27:54 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 110.888259 ms

2013-03-18 12:30:03 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 217.106479 ms

2013-03-18 12:30:08 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 249.5125 ms

2013-03-18 12:30:09 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 253.104289 ms

2013-03-18 12:30:10 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 265.351948 ms

2013-03-18 12:30:11 [iNFO] [sTDOUT] Something's taking too long! 'root' took aprox 261.544011 ms

2013-03-18 12:31:42 [FINEST] [mcp] Sending event FMLServerStoppingEvent to mod mcp

2013-03-18 12:31:42 [FINEST] [mcp] Sent event FMLServerStoppingEvent to mod mcp

2013-03-18 12:31:42 [FINEST] [FML] Sending event FMLServerStoppingEvent to mod FML

2013-03-18 12:31:42 [FINEST] [FML] Sent event FMLServerStoppingEvent to mod FML

2013-03-18 12:31:42 [FINEST] [Forge] Sending event FMLServerStoppingEvent to mod Forge

2013-03-18 12:31:42 [FINEST] [Forge] Sent event FMLServerStoppingEvent to mod Forge

2013-03-18 12:31:42 [iNFO] [sTDOUT] Stopping server connection

2013-03-18 12:31:42 [iNFO] [sTDERR] java.net.SocketException: socket closed

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.DualStackPlainSocketImpl.accept0(Native Method)

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.DualStackPlainSocketImpl.socketAccept(Unknown Source)

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.AbstractPlainSocketImpl.accept(Unknown Source)

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.PlainSocketImpl.accept(Unknown Source)

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.ServerSocket.implAccept(Unknown Source)

2013-03-18 12:31:42 [iNFO] [sTDERR] at java.net.ServerSocket.accept(Unknown Source)

2013-03-18 12:31:42 [iNFO] [sTDERR] at hu.run(ServerListenThread.java:81)

2013-03-18 12:31:42 [iNFO] [sTDOUT] Closing listening thread

2013-03-18 12:31:53 [iNFO] [ForgeModLoader] Unloading dimension 0

2013-03-18 12:31:53 [iNFO] [ForgeModLoader] Unloading dimension -1

2013-03-18 12:31:53 [iNFO] [ForgeModLoader] Unloading dimension 1

2013-03-18 12:31:53 [FINEST] [mcp] Sending event FMLServerStoppedEvent to mod mcp

2013-03-18 12:31:53 [FINEST] [mcp] Sent event FMLServerStoppedEvent to mod mcp

2013-03-18 12:31:53 [FINEST] [FML] Sending event FMLServerStoppedEvent to mod FML

2013-03-18 12:31:53 [FINEST] [FML] Sent event FMLServerStoppedEvent to mod FML

2013-03-18 12:31:53 [FINEST] [Forge] Sending event FMLServerStoppedEvent to mod Forge

2013-03-18 12:31:53 [FINEST] [Forge] Sent event FMLServerStoppedEvent to mod Forge

2013-03-18 12:33:17 [iNFO] [sTDOUT] Stopping!

2013-03-18 12:33:17 [iNFO] [sTDOUT] SoundSystem shutting down...

2013-03-18 12:33:17 [iNFO] [sTDOUT]    Author: Paul Lamb, www.paulscode.com

 

 

Of course, I realize that additional testing is warranted. I'll report back on whether 1.5 with the latest version of forge still has the problem, and I'll try getting a completely clean copy of Minecraft without MultiMC set up as soon as I have time, to see if MultiMC is somehow at fault.

 

That said, if anyone has any ideas as to the cause, I'd love to know what I can do to resolve the problem. Thanks for taking the time to look at this error.

[Longwing]

I've done some additional testing. I was able to reproduce the error in a MultiMC instance of Minecraft 1.5 with Forge 7.7.0.594 installed. The log is different, but the behavior is exactly the same. No drop in frame rate, but a delay of about 5 seconds between action and reaction about once per minute.

 

To eliminate any possibility of a performance issue, I handed the instance 2-4 gigs of ram in setup. As I tested, I also steadily disabled every single graphical setting. By the end of the log file, we're seeing a Graphics: Fast, Render Distance: Tiny, with no clouds, no v-sync, and reduced particles (Keeping in mind that my normal client for actually playing uses Render Far with shaders, and never experiences any problems).

 

Here's the logfile from the test:

 

 

2013-03-18 15:26:58 [iNFO] [ForgeModLoader] Forge Mod Loader version 5.0.31.594 for Minecraft 1.5 loading

2013-03-18 15:26:58 [iNFO] [ForgeModLoader] Java is Java HotSpot 64-Bit Server VM, version 1.7.0_17, running on Windows 7:amd64:6.1, installed at C:\Program Files\Java\jre7

2013-03-18 15:26:58 [FINE] [ForgeModLoader] Java classpath at launch is MultiMCLauncher.jar

2013-03-18 15:26:58 [FINE] [ForgeModLoader] Java library path at launch is C:\Program Files\Java\jre7\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Program Files\Common Files\Microsoft Shared\Windows Live;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Scrivener\aspell;C:\Program Files (x86)\Scrivener\aspell\bin;C:\Program Files (x86)\Calibre2\;C:\Program Files (x86);C:\Program Files;E:\Steam\steamapps\common;E:\Programs;C:\Program Files\Common Files\Microsoft Shared\Windows Live;C:\Program Files (x86)\Scrivener;C:\Program Files (x86)\Scrivener\Aspell;C:\Program Files (x86)\Scrivener\Aspell\bin;C:\Program Files (x86)\ftldat;.

2013-03-18 15:26:58 [FINE] [ForgeModLoader] Enabling runtime deobfuscation

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] All core mods are successfully located

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Discovering coremods

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file argo-small-3.2.jar present and correct in lib dir

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file guava-14.0-rc3.jar present and correct in lib dir

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file asm-all-4.1.jar present and correct in lib dir

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file bcprov-jdk15on-148.jar present and correct in lib dir

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file deobfuscation_data_1.5.zip present and correct in lib dir

2013-03-18 15:26:58 [FINEST] [ForgeModLoader] Found library file scala-library.jar present and correct in lib dir

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Running coremod plugins

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Running coremod plugin FMLCorePlugin

2013-03-18 15:26:59 [iNFO] [ForgeModLoader] Found valid fingerprint for Minecraft Forge. Certificate fingerprint de4cf8a3f3bc15635810044c39240bf96804ea7d

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Coremod plugin FMLCorePlugin run successfully

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Running coremod plugin FMLForgePlugin

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Coremod plugin FMLForgePlugin run successfully

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Validating minecraft

2013-03-18 15:26:59 [FINEST] [ForgeModLoader] Minecraft validated, launching...

2013-03-18 15:27:00 [iNFO] [sTDOUT] 229 recipes

2013-03-18 15:27:00 [iNFO] [sTDOUT] 27 achievements

2013-03-18 15:27:00 [iNFO] [Minecraft-Client] Setting user: longwing

2013-03-18 15:27:00 [iNFO] [sTDOUT] (Session ID is -###########)

2013-03-18 15:27:00 [iNFO] [Minecraft-Client] LWJGL Version: 2.4.2

2013-03-18 15:27:01 [iNFO] [MinecraftForge] Attempting early MinecraftForge initialization

2013-03-18 15:27:01 [iNFO] [sTDOUT] MinecraftForge v7.7.0.594 Initialized

2013-03-18 15:27:01 [iNFO] [ForgeModLoader] MinecraftForge v7.7.0.594 Initialized

2013-03-18 15:27:01 [iNFO] [sTDOUT] Replaced 85 ore recipies

2013-03-18 15:27:01 [iNFO] [MinecraftForge] Completed early MinecraftForge initialization

2013-03-18 15:27:01 [iNFO] [ForgeModLoader] Reading custom logging properties from E:\MultiMC\instances\Forge Testing 1.5\minecraft\config\logging.properties

2013-03-18 15:27:01 [OFF] [ForgeModLoader] Logging level for ForgeModLoader logging is set to ALL

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Building injected Mod Containers [cpw.mods.fml.common.FMLDummyContainer, net.minecraftforge.common.ForgeDummyContainer]

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Attempting to load mods contained in the minecraft jar file and associated classes

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Found a minecraft related file at E:\MultiMC\instances\Forge Testing 1.5\minecraft\bin\minecraft.jar, examining for mod candidates

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\bin\lwjgl.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\bin\lwjgl_util.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\bin\jinput.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\argo-small-3.2.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\guava-14.0-rc3.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\asm-all-4.1.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\bcprov-jdk15on-148.jar

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\deobfuscation_data_1.5.zip

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Skipping known library file E:\MultiMC\instances\Forge Testing 1.5\minecraft\lib\scala-library.jar

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Minecraft jar mods loaded successfully

2013-03-18 15:27:01 [iNFO] [ForgeModLoader] Searching E:\MultiMC\instances\Forge Testing 1.5\minecraft\mods for mods

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Examining file minecraft.jar for potential mods

2013-03-18 15:27:01 [FINE] [ForgeModLoader] The mod container minecraft.jar appears to be missing an mcmod.info file

2013-03-18 15:27:01 [iNFO] [ForgeModLoader] Forge Mod Loader has identified 3 mods to load

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Received a system property request ''

2013-03-18 15:27:01 [FINER] [ForgeModLoader] System property request managing the state of 0 mods

2013-03-18 15:27:01 [FINE] [ForgeModLoader] After merging, found state information for 0 mods

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Reloading logging properties from E:\MultiMC\instances\Forge Testing 1.5\minecraft\config\logging.properties

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Reloaded logging properties

2013-03-18 15:27:01 [FINE] [mcp] Mod Logging channel mcp configured at default level.

2013-03-18 15:27:01 [iNFO] [mcp] Activating mod mcp

2013-03-18 15:27:01 [FINE] [FML] Mod Logging channel FML configured at default level.

2013-03-18 15:27:01 [iNFO] [FML] Activating mod FML

2013-03-18 15:27:01 [FINE] [Forge] Mod Logging channel Forge configured at default level.

2013-03-18 15:27:01 [iNFO] [Forge] Activating mod Forge

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Verifying mod requirements are satisfied

2013-03-18 15:27:01 [FINER] [ForgeModLoader] All mod requirements are satisfied

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Sorting mods into an ordered list

2013-03-18 15:27:01 [FINER] [ForgeModLoader] Mod sorting completed successfully

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Mod sorting data

2013-03-18 15:27:01 [FINE] [ForgeModLoader] No user mods found to sort

2013-03-18 15:27:01 [FINEST] [mcp] Sending event FMLConstructionEvent to mod mcp

2013-03-18 15:27:01 [FINEST] [mcp] Sent event FMLConstructionEvent to mod mcp

2013-03-18 15:27:01 [FINEST] [FML] Sending event FMLConstructionEvent to mod FML

2013-03-18 15:27:01 [FINEST] [FML] Sent event FMLConstructionEvent to mod FML

2013-03-18 15:27:01 [FINEST] [Forge] Sending event FMLConstructionEvent to mod Forge

2013-03-18 15:27:01 [FINEST] [Forge] Sent event FMLConstructionEvent to mod Forge

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Mod signature data

2013-03-18 15:27:01 [FINE] [ForgeModLoader] mcp(Minecraft Coder Pack:7.42): minecraft.jar (NO VALID CERTIFICATE FOUND)

2013-03-18 15:27:01 [FINE] [ForgeModLoader] FML(Forge Mod Loader:5.0.31.594): coremods (de4cf8a3f3bc15635810044c39240bf96804ea7d)

2013-03-18 15:27:01 [FINE] [ForgeModLoader] Forge(Minecraft Forge:7.7.0.594): coremods (NO VALID CERTIFICATE FOUND)

2013-03-18 15:27:01 [FINEST] [mcp] Sending event FMLPreInitializationEvent to mod mcp

2013-03-18 15:27:01 [FINEST] [mcp] Sent event FMLPreInitializationEvent to mod mcp

2013-03-18 15:27:01 [FINEST] [FML] Sending event FMLPreInitializationEvent to mod FML

2013-03-18 15:27:01 [FINEST] [FML] Sent event FMLPreInitializationEvent to mod FML

2013-03-18 15:27:01 [FINEST] [Forge] Sending event FMLPreInitializationEvent to mod Forge

2013-03-18 15:27:01 [iNFO] [ForgeModLoader] Configured a dormant chunk cache size of 0

2013-03-18 15:27:01 [FINEST] [Forge] Sent event FMLPreInitializationEvent to mod Forge

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/lava_flow.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/water_flow.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/fire_0.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/fire_1.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/lava.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/portal.txt

2013-03-18 15:27:03 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/water.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/items/clock.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/items/compass.txt

2013-03-18 15:27:04 [FINEST] [mcp] Sending event FMLInitializationEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [mcp] Sent event FMLInitializationEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [FML] Sending event FMLInitializationEvent to mod FML

2013-03-18 15:27:04 [FINEST] [FML] Sent event FMLInitializationEvent to mod FML

2013-03-18 15:27:04 [FINEST] [Forge] Sending event FMLInitializationEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [Forge] Sent event FMLInitializationEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod mcp

2013-03-18 15:27:04 [FINEST] [mcp] Sending event IMCEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [mcp] Sent event IMCEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod FML

2013-03-18 15:27:04 [FINEST] [FML] Sending event IMCEvent to mod FML

2013-03-18 15:27:04 [FINEST] [FML] Sent event IMCEvent to mod FML

2013-03-18 15:27:04 [FINEST] [ForgeModLoader] Attempting to deliver 0 IMC messages to mod Forge

2013-03-18 15:27:04 [FINEST] [Forge] Sending event IMCEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [Forge] Sent event IMCEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [mcp] Sending event FMLPostInitializationEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [mcp] Sent event FMLPostInitializationEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [FML] Sending event FMLPostInitializationEvent to mod FML

2013-03-18 15:27:04 [FINEST] [FML] Sent event FMLPostInitializationEvent to mod FML

2013-03-18 15:27:04 [FINEST] [Forge] Sending event FMLPostInitializationEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [Forge] Sent event FMLPostInitializationEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [mcp] Sending event FMLLoadCompleteEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [mcp] Sent event FMLLoadCompleteEvent to mod mcp

2013-03-18 15:27:04 [FINEST] [FML] Sending event FMLLoadCompleteEvent to mod FML

2013-03-18 15:27:04 [FINEST] [FML] Sent event FMLLoadCompleteEvent to mod FML

2013-03-18 15:27:04 [FINEST] [Forge] Sending event FMLLoadCompleteEvent to mod Forge

2013-03-18 15:27:04 [FINEST] [Forge] Sent event FMLLoadCompleteEvent to mod Forge

2013-03-18 15:27:04 [iNFO] [ForgeModLoader] Forge Mod Loader has successfully loaded 3 mods

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/lava_flow.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/water_flow.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/fire_0.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/fire_1.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/lava.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/portal.txt

2013-03-18 15:27:04 [iNFO] [Minecraft-Client] Found animation info for: textures/blocks/water.txt

2013-03-18 15:27:05 [iNFO] [Minecraft-Client] Found animation info for: textures/items/clock.txt

2013-03-18 15:27:05 [iNFO] [Minecraft-Client] Found animation info for: textures/items/compass.txt

2013-03-18 15:27:12 [iNFO] [Minecraft-Server] Starting integrated minecraft server version 1.5

2013-03-18 15:27:12 [iNFO] [Minecraft-Server] Generating keypair

2013-03-18 15:27:12 [FINEST] [mcp] Sending event FMLServerAboutToStartEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [mcp] Sent event FMLServerAboutToStartEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [FML] Sending event FMLServerAboutToStartEvent to mod FML

2013-03-18 15:27:12 [FINEST] [FML] Sent event FMLServerAboutToStartEvent to mod FML

2013-03-18 15:27:12 [FINEST] [Forge] Sending event FMLServerAboutToStartEvent to mod Forge

2013-03-18 15:27:12 [FINEST] [Forge] Sent event FMLServerAboutToStartEvent to mod Forge

2013-03-18 15:27:12 [FINE] [fml.ItemTracker] The difference set is equal

2013-03-18 15:27:12 [iNFO] [ForgeModLoader] Loading dimension 0 (New World) (net.minecraft.server.integrated.IntegratedServer@1d3e2b9a)

2013-03-18 15:27:12 [iNFO] [ForgeModLoader] Loading dimension 1 (New World) (net.minecraft.server.integrated.IntegratedServer@1d3e2b9a)

2013-03-18 15:27:12 [iNFO] [ForgeModLoader] Loading dimension -1 (New World) (net.minecraft.server.integrated.IntegratedServer@1d3e2b9a)

2013-03-18 15:27:12 [iNFO] [Minecraft-Server] Preparing start region for level 0

2013-03-18 15:27:12 [FINEST] [mcp] Sending event FMLServerStartingEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [mcp] Sent event FMLServerStartingEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [FML] Sending event FMLServerStartingEvent to mod FML

2013-03-18 15:27:12 [FINEST] [FML] Sent event FMLServerStartingEvent to mod FML

2013-03-18 15:27:12 [FINEST] [Forge] Sending event FMLServerStartingEvent to mod Forge

2013-03-18 15:27:12 [FINEST] [Forge] Sent event FMLServerStartingEvent to mod Forge

2013-03-18 15:27:12 [FINEST] [mcp] Sending event FMLServerStartedEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [mcp] Sent event FMLServerStartedEvent to mod mcp

2013-03-18 15:27:12 [FINEST] [FML] Sending event FMLServerStartedEvent to mod FML

2013-03-18 15:27:12 [FINEST] [FML] Sent event FMLServerStartedEvent to mod FML

2013-03-18 15:27:12 [FINEST] [Forge] Sending event FMLServerStartedEvent to mod Forge

2013-03-18 15:27:12 [FINEST] [Forge] Sent event FMLServerStartedEvent to mod Forge

2013-03-18 15:27:13 [iNFO] [Minecraft-Server] longwing[/127.0.0.1:0] logged in with entity id 333 at (436.2824484264283, 71.0, -93.4813065419769)

2013-03-18 15:27:13 [iNFO] [Minecraft-Server] Saving and pausing game...

2013-03-18 15:27:13 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:27:13 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:27:13 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:27:21 [iNFO] [Minecraft-Server] Saving and pausing game...

2013-03-18 15:27:26 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:27:31 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:27:31 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:27:31 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:28:00 [iNFO] [Minecraft-Server] Saving and pausing game...

2013-03-18 15:28:05 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:28:15 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:28:15 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:28:15 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:28:51 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:29:49 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:29:49 [iNFO] [Minecraft-Server] Saving and pausing game...

2013-03-18 15:29:49 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:29:54 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:29:54 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:29:54 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:31:09 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:32:07 [WARNING] [Minecraft-Server] Can't keep up! Did the system time change, or is the server overloaded?

2013-03-18 15:32:17 [iNFO] [Minecraft-Server] Saving and pausing game...

2013-03-18 15:32:22 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:32:32 [FINEST] [mcp] Sending event FMLServerStoppingEvent to mod mcp

2013-03-18 15:32:32 [FINEST] [mcp] Sent event FMLServerStoppingEvent to mod mcp

2013-03-18 15:32:32 [FINEST] [FML] Sending event FMLServerStoppingEvent to mod FML

2013-03-18 15:32:32 [FINEST] [FML] Sent event FMLServerStoppingEvent to mod FML

2013-03-18 15:32:32 [FINEST] [Forge] Sending event FMLServerStoppingEvent to mod Forge

2013-03-18 15:32:32 [FINEST] [Forge] Sent event FMLServerStoppingEvent to mod Forge

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Stopping server

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Saving players

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Saving worlds

2013-03-18 15:32:32 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Overworld

2013-03-18 15:32:37 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/Nether

2013-03-18 15:32:37 [iNFO] [Minecraft-Server] Saving chunks for level 'New World'/The End

2013-03-18 15:32:37 [iNFO] [ForgeModLoader] Unloading dimension 0

2013-03-18 15:32:37 [iNFO] [ForgeModLoader] Unloading dimension -1

2013-03-18 15:32:37 [iNFO] [ForgeModLoader] Unloading dimension 1

2013-03-18 15:32:37 [FINEST] [mcp] Sending event FMLServerStoppedEvent to mod mcp

2013-03-18 15:32:37 [FINEST] [mcp] Sent event FMLServerStoppedEvent to mod mcp

2013-03-18 15:32:37 [FINEST] [FML] Sending event FMLServerStoppedEvent to mod FML

2013-03-18 15:32:37 [FINEST] [FML] Sent event FMLServerStoppedEvent to mod FML

2013-03-18 15:32:37 [FINEST] [Forge] Sending event FMLServerStoppedEvent to mod Forge

2013-03-18 15:32:37 [FINEST] [Forge] Sent event FMLServerStoppedEvent to mod Forge

2013-03-18 15:32:42 [iNFO] [Minecraft-Client] Stopping!