MxGZwz6p

1[20:45:55] [main/DEBUG] [FML/]: Injecting tracing printstreams for STDOUT/STDERR.
2[20:45:55] [main/INFO] [FML/]: Forge Mod Loader version 12.16.0.1780 for Minecraft 1.9 loading
3[20:45:55] [main/INFO] [FML/]: Java is Java HotSpot(TM) 64-Bit Server VM, version 1.8.0_72, running on Linux:amd64:3.14.32-xxxx-grs-ipv6-64, installed at /usr/lib/jvm/java-8-oracle/jre
4[20:45:55] [main/DEBUG] [FML/]: Java classpath at launch is forge-1.9-12.16.0.1780-1.9-universal.jar
5[20:45:55] [main/DEBUG] [FML/]: Java library path at launch is /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
6[20:45:55] [main/DEBUG] [FML/]: Enabling runtime deobfuscation
7[20:45:55] [main/DEBUG] [FML/]: Instantiating coremod class FMLCorePlugin
8[20:45:55] [main/DEBUG] [FML/]: Added access transformer class net.minecraftforge.fml.common.asm.transformers.AccessTransformer to enqueued access transformers
9[20:45:55] [main/DEBUG] [FML/]: Enqueued coremod FMLCorePlugin
10[20:45:55] [main/DEBUG] [FML/]: Instantiating coremod class FMLForgePlugin
11[20:45:55] [main/DEBUG] [FML/]: Enqueued coremod FMLForgePlugin
12[20:45:55] [main/DEBUG] [FML/]: All fundamental core mods are successfully located
13[20:45:55] [main/DEBUG] [FML/]: Attempting to load commandline specified mods, relative to /home/jamefrus/econ/.
14[20:45:55] [main/DEBUG] [FML/]: Discovering coremods
15[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy command-core-3.1.2.jar
16[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in command-core-3.1.2.jar
17[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy command-forge-1.0.1.jar
18[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in command-forge-1.0.1.jar
19[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy commands-foundation-1.0.0-4.jar
20[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in commands-foundation-1.0.0-4.jar
21[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy common-core-0.1.6.jar
22[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in common-core-0.1.6.jar
23[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy common-forge-1.0.0.jar
24[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in common-forge-1.0.0.jar
25[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy common-util-0.2.0.jar
26[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in common-util-0.2.0.jar
27[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy kotlin-runtime-1.0.1-1.jar
28[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in kotlin-runtime-1.0.1-1.jar
29[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy kotlin-stdlib-1.0.1-1.jar
30[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in kotlin-stdlib-1.0.1-1.jar
31[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy mysql-connector-java-5.1.37.jar
32[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in mysql-connector-java-5.1.37.jar
33[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy ormlite-core-4.48.jar
34[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in ormlite-core-4.48.jar
35[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy ormlite-jdbc-4.48.jar
36[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in ormlite-jdbc-4.48.jar
37[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy permissionforgecommands-1.0.0-4.jar
38[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in permissionforgecommands-1.0.0-4.jar
39[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy permissions-v1.0.0-b14.jar
40[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in permissions-v1.0.0-b14.jar
41[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy permissionsforge-1.0.0-5.jar
42[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in permissionsforge-1.0.0-5.jar
43[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy rebin-1.0.1-12.jar
44[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in rebin-1.0.1-12.jar
45[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy server-i18n-1.0.1-2.jar
46[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in server-i18n-1.0.1-2.jar
47[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy skyecon-1.2.2.jar
48[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in skyecon-1.2.2.jar
49[20:45:55] [main/DEBUG] [FML/]: Examining for coremod candidacy sqlite-jdbc-3.8.11.2.jar
50[20:45:55] [main/DEBUG] [FML/]: Not found coremod data in sqlite-jdbc-3.8.11.2.jar
51[20:45:55] [main/INFO] [LaunchWrapper/]: Loading tweak class name net.minecraftforge.fml.common.launcher.FMLInjectionAndSortingTweaker
52[20:45:55] [main/INFO] [LaunchWrapper/]: Loading tweak class name net.minecraftforge.fml.common.launcher.FMLDeobfTweaker
53[20:45:55] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.common.launcher.FMLInjectionAndSortingTweaker
54[20:45:55] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.common.launcher.FMLInjectionAndSortingTweaker
55[20:45:55] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.relauncher.CoreModManager$FMLPluginWrapper
56[20:45:55] [main/DEBUG] [FML/]: Injecting coremod FMLCorePlugin {net.minecraftforge.fml.relauncher.FMLCorePlugin} class transformers
57[20:45:55] [main/TRACE] [FML/]: Registering transformer net.minecraftforge.fml.common.asm.transformers.BlamingTransformer
58[20:45:55] [main/TRACE] [FML/]: Registering transformer net.minecraftforge.fml.common.asm.transformers.SideTransformer
59[20:45:55] [main/TRACE] [FML/]: Registering transformer net.minecraftforge.fml.common.asm.transformers.EventSubscriptionTransformer
60[20:45:55] [main/TRACE] [FML/]: Registering transformer net.minecraftforge.fml.common.asm.transformers.EventSubscriberTransformer
61[20:45:55] [main/DEBUG] [FML/]: Injection complete
62[20:45:55] [main/DEBUG] [FML/]: Running coremod plugin for FMLCorePlugin {net.minecraftforge.fml.relauncher.FMLCorePlugin}
63[20:45:55] [main/DEBUG] [FML/]: Running coremod plugin FMLCorePlugin
64[20:45:56] [main/DEBUG] [FML/]: Read 801 binary patches
65[20:45:56] [main/DEBUG] [FML/]: Loading deobfuscation resource /deobfuscation_data-1.9.lzma with 31805 records
66[20:45:56] [main/INFO] [FML/]: Found valid fingerprint for Minecraft Forge. Certificate fingerprint e3c3d50c7c986df74c645c0ac54639741c90a557
67[20:45:56] [main/DEBUG] [FML/]: Coremod plugin class FMLCorePlugin run successfully
68[20:45:56] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.relauncher.CoreModManager$FMLPluginWrapper
69[20:45:56] [main/DEBUG] [FML/]: Injecting coremod FMLForgePlugin {net.minecraftforge.classloading.FMLForgePlugin} class transformers
70[20:45:56] [main/DEBUG] [FML/]: Injection complete
71[20:45:56] [main/DEBUG] [FML/]: Running coremod plugin for FMLForgePlugin {net.minecraftforge.classloading.FMLForgePlugin}
72[20:45:56] [main/DEBUG] [FML/]: Running coremod plugin FMLForgePlugin
73[20:45:56] [main/DEBUG] [FML/]: Coremod plugin class FMLForgePlugin run successfully
74[20:45:56] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.common.launcher.FMLDeobfTweaker
75[20:45:56] [main/DEBUG] [FML/]: Loaded 183 rules from AccessTransformer config file forge_at.cfg
76[20:45:56] [main/DEBUG] [FML/]: Validating minecraft
77[20:45:56] [main/DEBUG] [FML/]: Minecraft validated, launching...
78[20:45:56] [main/INFO] [LaunchWrapper/]: Loading tweak class name net.minecraftforge.fml.common.launcher.TerminalTweaker
79[20:45:56] [main/INFO] [LaunchWrapper/]: Calling tweak class net.minecraftforge.fml.common.launcher.TerminalTweaker
80[20:45:56] [main/INFO] [LaunchWrapper/]: Launching wrapped minecraft {net.minecraft.server.MinecraftServer}
81[20:46:00] [Server thread/INFO] [FML/]: MinecraftForge v12.16.0.1780 Initialized
82[20:46:00] [Server thread/INFO] [FML/]: Replaced 207 ore recipies
83[20:46:00] [Server thread/DEBUG] [FML/]: File /home/jamefrus/econ/config/injectedDependencies.json not found. No dependencies injected
84[20:46:00] [Server thread/DEBUG] [FML/]: Building injected Mod Containers [net.minecraftforge.fml.common.FMLContainer, net.minecraftforge.common.ForgeModContainer]
85[20:46:00] [Server thread/DEBUG] [FML/]: Attempting to load mods contained in the minecraft jar file and associated classes
86[20:46:00] [Server thread/DEBUG] [FML/]: Minecraft is a file at /home/jamefrus/econ/forge-1.9-12.16.0.1780-1.9-universal.jar, loading
87[20:46:00] [Server thread/DEBUG] [FML/]: Minecraft jar mods loaded successfully
88[20:46:00] [Server thread/INFO] [FML/]: Found 0 mods from the command line. Injecting into mod discoverer
89[20:46:00] [Server thread/INFO] [FML/]: Searching /home/jamefrus/econ/mods for mods
90[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file command-core-3.1.2.jar
91[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file command-forge-1.0.1.jar
92[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file commands-foundation-1.0.0-4.jar
93[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file common-core-0.1.6.jar
94[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file common-forge-1.0.0.jar
95[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file common-util-0.2.0.jar
96[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file kotlin-runtime-1.0.1-1.jar
97[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file kotlin-stdlib-1.0.1-1.jar
98[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file mysql-connector-java-5.1.37.jar
99[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file ormlite-core-4.48.jar
100[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file ormlite-jdbc-4.48.jar
101[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file permissionforgecommands-1.0.0-4.jar
102[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file permissions-v1.0.0-b14.jar
103[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file permissionsforge-1.0.0-5.jar
104[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file rebin-1.0.1-12.jar
105[20:46:00] [Server thread/DEBUG] [FML/]: Ignoring unknown file resolution.cache in mods directory
106[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file server-i18n-1.0.1-2.jar
107[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file skyecon-1.2.2.jar
108[20:46:00] [Server thread/DEBUG] [FML/]: Found a candidate zip or jar file sqlite-jdbc-3.8.11.2.jar
109[20:46:00] [Server thread/DEBUG] [FML/]: Examining file forge-1.9-12.16.0.1780-1.9-universal.jar for potential mods
110[20:46:00] [Server thread/DEBUG] [FML/]: The mod container forge-1.9-12.16.0.1780-1.9-universal.jar appears to be missing an mcmod.info file
111[20:46:00] [Server thread/DEBUG] [FML/]: Examining file command-core-3.1.2.jar for potential mods
112[20:46:00] [Server thread/DEBUG] [FML/]: The mod container command-core-3.1.2.jar appears to be missing an mcmod.info file
113[20:46:00] [Server thread/DEBUG] [FML/]: Examining file command-forge-1.0.1.jar for potential mods
114[20:46:00] [Server thread/DEBUG] [FML/]: The mod container command-forge-1.0.1.jar appears to be missing an mcmod.info file
115[20:46:00] [Server thread/DEBUG] [FML/]: Examining file commands-foundation-1.0.0-4.jar for potential mods
116[20:46:00] [Server thread/TRACE] [FML/]: Located mcmod.info file in file commands-foundation-1.0.0-4.jar
117[20:46:00] [Server thread/DEBUG] [FML/]: Identified a mod of type Lnet/minecraftforge/fml/common/Mod; (ru.skymine.commands.Commands) - loading
118[20:46:00] [Server thread/TRACE] [commands/]: Parsed dependency info : [server-i18n] [server-i18n] []
119[20:46:00] [Server thread/INFO] [commands/]: Mod commands is missing the required element 'name'. Substituting commands
120[20:46:00] [Server thread/DEBUG] [commands/]: Attempting to load the file version.properties from commands-foundation-1.0.0-4.jar to locate a version number for commands
121[20:46:00] [Server thread/WARN] [commands/]: Mod commands is missing the required element 'version' and no fallback can be found. Substituting '1.0'.
122[20:46:00] [Server thread/DEBUG] [FML/]: Examining file common-core-0.1.6.jar for potential mods
123[20:46:00] [Server thread/DEBUG] [FML/]: The mod container common-core-0.1.6.jar appears to be missing an mcmod.info file
124[20:46:00] [Server thread/DEBUG] [FML/]: Examining file common-forge-1.0.0.jar for potential mods
125[20:46:00] [Server thread/DEBUG] [FML/]: The mod container common-forge-1.0.0.jar appears to be missing an mcmod.info file
126[20:46:00] [Server thread/DEBUG] [FML/]: Examining file common-util-0.2.0.jar for potential mods
127[20:46:00] [Server thread/DEBUG] [FML/]: The mod container common-util-0.2.0.jar appears to be missing an mcmod.info file
128[20:46:00] [Server thread/DEBUG] [FML/]: Examining file kotlin-runtime-1.0.1-1.jar for potential mods
129[20:46:00] [Server thread/DEBUG] [FML/]: The mod container kotlin-runtime-1.0.1-1.jar appears to be missing an mcmod.info file
130[20:46:00] [Server thread/DEBUG] [FML/]: Examining file kotlin-stdlib-1.0.1-1.jar for potential mods
131[20:46:00] [Server thread/DEBUG] [FML/]: The mod container kotlin-stdlib-1.0.1-1.jar appears to be missing an mcmod.info file
132[20:46:01] [Server thread/DEBUG] [FML/]: Examining file mysql-connector-java-5.1.37.jar for potential mods
133[20:46:01] [Server thread/DEBUG] [FML/]: The mod container mysql-connector-java-5.1.37.jar appears to be missing an mcmod.info file
134[20:46:01] [Server thread/DEBUG] [FML/]: Examining file ormlite-core-4.48.jar for potential mods
135[20:46:01] [Server thread/DEBUG] [FML/]: The mod container ormlite-core-4.48.jar appears to be missing an mcmod.info file
136[20:46:01] [Server thread/DEBUG] [FML/]: Examining file ormlite-jdbc-4.48.jar for potential mods
137[20:46:01] [Server thread/DEBUG] [FML/]: The mod container ormlite-jdbc-4.48.jar appears to be missing an mcmod.info file
138[20:46:01] [Server thread/DEBUG] [FML/]: Examining file permissionforgecommands-1.0.0-4.jar for potential mods
139[20:46:01] [Server thread/DEBUG] [FML/]: The mod container permissionforgecommands-1.0.0-4.jar appears to be missing an mcmod.info file
140[20:46:01] [Server thread/DEBUG] [FML/]: Identified a mod of type Lnet/minecraftforge/fml/common/Mod; (ru.skymine.permissions.PermissionCommands) - loading
141[20:46:01] [Server thread/TRACE] [permissions-commands/]: Parsed dependency info : [] [] []
142[20:46:01] [Server thread/INFO] [permissions-commands/]: Mod permissions-commands is missing the required element 'name'. Substituting permissions-commands
143[20:46:01] [Server thread/DEBUG] [permissions-commands/]: Attempting to load the file version.properties from permissionforgecommands-1.0.0-4.jar to locate a version number for permissions-commands
144[20:46:01] [Server thread/WARN] [permissions-commands/]: Mod permissions-commands is missing the required element 'version' and no fallback can be found. Substituting '1.0'.
145[20:46:01] [Server thread/DEBUG] [FML/]: Examining file permissions-v1.0.0-b14.jar for potential mods
146[20:46:01] [Server thread/DEBUG] [FML/]: The mod container permissions-v1.0.0-b14.jar appears to be missing an mcmod.info file
147[20:46:01] [Server thread/DEBUG] [FML/]: Examining file permissionsforge-1.0.0-5.jar for potential mods
148[20:46:01] [Server thread/DEBUG] [FML/]: The mod container permissionsforge-1.0.0-5.jar appears to be missing an mcmod.info file
149[20:46:01] [Server thread/DEBUG] [FML/]: Identified a mod of type Lnet/minecraftforge/fml/common/Mod; (ru.skymine.permissions.Permissions) - loading
150[20:46:01] [Server thread/TRACE] [permissions/]: Parsed dependency info : [] [] []
151[20:46:01] [Server thread/DEBUG] [permissions/]: Attempting to load the file version.properties from permissionsforge-1.0.0-5.jar to locate a version number for permissions
152[20:46:01] [Server thread/WARN] [permissions/]: Mod permissions is missing the required element 'version' and no fallback can be found. Substituting '1.0'.
153[20:46:01] [Server thread/DEBUG] [FML/]: Examining file rebin-1.0.1-12.jar for potential mods
154[20:46:01] [Server thread/DEBUG] [FML/]: The mod container rebin-1.0.1-12.jar appears to be missing an mcmod.info file
155[20:46:01] [Server thread/DEBUG] [FML/]: Examining file server-i18n-1.0.1-2.jar for potential mods
156[20:46:01] [Server thread/DEBUG] [FML/]: The mod container server-i18n-1.0.1-2.jar appears to be missing an mcmod.info file
157[20:46:01] [Server thread/DEBUG] [FML/]: Identified a mod of type Lnet/minecraftforge/fml/common/Mod; (ru.skymine.i18n.LocalizationLoader) - loading
158[20:46:01] [Server thread/TRACE] [server-i18n/]: Parsed dependency info : [] [] []
159[20:46:01] [Server thread/INFO] [server-i18n/]: Mod server-i18n is missing the required element 'name'. Substituting server-i18n
160[20:46:01] [Server thread/DEBUG] [server-i18n/]: Attempting to load the file version.properties from server-i18n-1.0.1-2.jar to locate a version number for server-i18n
161[20:46:01] [Server thread/WARN] [server-i18n/]: Mod server-i18n is missing the required element 'version' and no fallback can be found. Substituting '1.0'.
162[20:46:01] [Server thread/DEBUG] [FML/]: Examining file skyecon-1.2.2.jar for potential mods
163[20:46:01] [Server thread/DEBUG] [FML/]: The mod container skyecon-1.2.2.jar appears to be missing an mcmod.info file
164[20:46:01] [Server thread/DEBUG] [FML/]: Identified a mod of type Lnet/minecraftforge/fml/common/Mod; (su.jfdev.skymine.econ.EconomyMod) - loading
165[20:46:01] [Server thread/TRACE] [SkyEcon/]: Parsed dependency info : [] [] []
166[20:46:01] [Server thread/INFO] [SkyEcon/]: Mod SkyEcon is missing the required element 'name'. Substituting SkyEcon
167[20:46:01] [Server thread/DEBUG] [FML/]: Examining file sqlite-jdbc-3.8.11.2.jar for potential mods
168[20:46:01] [Server thread/DEBUG] [FML/]: The mod container sqlite-jdbc-3.8.11.2.jar appears to be missing an mcmod.info file
169[20:46:01] [Server thread/INFO] [FML/]: Forge Mod Loader has identified 8 mods to load
170[20:46:01] [Server thread/TRACE] [FML/]: Received a system property request ''
171[20:46:01] [Server thread/TRACE] [FML/]: System property request managing the state of 0 mods
172[20:46:01] [Server thread/DEBUG] [FML/]: After merging, found state information for 0 mods
173[20:46:01] [Server thread/DEBUG] [FML/]: Found translations in forge-1.9-12.16.0.1780-1.9-universal.jar [af_ZA, ar_SA, br_FR, ca_ES, cs_CZ, da_DK, de_DE, el_GR, en_US, es_ES, fa_IR, fi_FI, fr_FR, he_IL, hu_HU, it_IT, ja_JP, ko_KR, lt_LT, nb_NO, nl_NL, nn_NO, no_NO, pl_PL, pt_BR, pt_PT, ro_RO, ru_RU, sl_SI, sr_SP, sv_SE, tr_TR, uk_UA, vi_VN, zh_CN, zh_TW]
174[20:46:01] [Server thread/DEBUG] [FML/]: Found translations in forge-1.9-12.16.0.1780-1.9-universal.jar [af_ZA, ar_SA, br_FR, ca_ES, cs_CZ, da_DK, de_DE, el_GR, en_US, es_ES, fa_IR, fi_FI, fr_FR, he_IL, hu_HU, it_IT, ja_JP, ko_KR, lt_LT, nb_NO, nl_NL, nn_NO, no_NO, pl_PL, pt_BR, pt_PT, ro_RO, ru_RU, sl_SI, sr_SP, sv_SE, tr_TR, uk_UA, vi_VN, zh_CN, zh_TW]
175[20:46:01] [Server thread/DEBUG] [commands/]: Enabling mod commands
176[20:46:01] [Server thread/DEBUG] [permissions-commands/]: Enabling mod permissions-commands
177[20:46:01] [Server thread/DEBUG] [permissions/]: Enabling mod permissions
178[20:46:01] [Server thread/DEBUG] [server-i18n/]: Enabling mod server-i18n
179[20:46:01] [Server thread/DEBUG] [SkyEcon/]: Enabling mod SkyEcon
180[20:46:01] [Server thread/TRACE] [FML/]: Verifying mod requirements are satisfied
181[20:46:01] [Server thread/TRACE] [FML/]: All mod requirements are satisfied
182[20:46:01] [Server thread/TRACE] [FML/]: Sorting mods into an ordered list
183[20:46:01] [Server thread/TRACE] [FML/]: Mod sorting completed successfully
184[20:46:01] [Server thread/DEBUG] [FML/]: Mod sorting data
185[20:46:01] [Server thread/DEBUG] [FML/]: 	server-i18n(server-i18n:1.0): server-i18n-1.0.1-2.jar ()
186[20:46:01] [Server thread/DEBUG] [FML/]: 	commands(commands:1.0): commands-foundation-1.0.0-4.jar (required-after:server-i18n)
187[20:46:01] [Server thread/DEBUG] [FML/]: 	permissions-commands(permissions-commands:1.0): permissionforgecommands-1.0.0-4.jar ()
188[20:46:01] [Server thread/DEBUG] [FML/]: 	permissions(Permissions:1.0): permissionsforge-1.0.0-5.jar ()
189[20:46:01] [Server thread/DEBUG] [FML/]: 	SkyEcon(SkyEcon:1.1.3): skyecon-1.2.2.jar ()
190[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file command-core-3.1.2.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
191[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file command-forge-1.0.1.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
192[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file common-core-0.1.6.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
193[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file common-forge-1.0.0.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
194[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file common-util-0.2.0.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
195[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file kotlin-runtime-1.0.1-1.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
196[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file kotlin-stdlib-1.0.1-1.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
197[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file mysql-connector-java-5.1.37.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
198[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file ormlite-core-4.48.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
199[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file ormlite-jdbc-4.48.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
200[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file permissions-v1.0.0-b14.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
201[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file rebin-1.0.1-12.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
202[20:46:01] [Server thread/INFO] [FML/]: FML has found a non-mod file sqlite-jdbc-3.8.11.2.jar in your mods directory. It will now be injected into your classpath. This could severe stability issues, it should be removed if possible.
203[20:46:01] [Server thread/TRACE] [mcp/mcp]: Sending event FMLConstructionEvent to mod mcp
204[20:46:01] [Server thread/TRACE] [mcp/mcp]: Sent event FMLConstructionEvent to mod mcp
205[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - Minecraft Coder Pack took 0.001s
206[20:46:01] [Server thread/TRACE] [FML/FML]: Sending event FMLConstructionEvent to mod FML
207[20:46:01] [Server thread/TRACE] [FML/FML]: Mod FML is using network checker : Invoking method checkModLists
208[20:46:01] [Server thread/TRACE] [FML/FML]: Testing mod FML to verify it accepts its own version in a remote connection
209[20:46:01] [Server thread/TRACE] [FML/FML]: The mod FML accepts its own version (8.0.99.99)
210[20:46:01] [Server thread/INFO] [FML/FML]: Attempting connection with missing mods [mcp, FML, Forge, commands, permissions-commands, permissions, server-i18n, SkyEcon] at CLIENT
211[20:46:01] [Server thread/INFO] [FML/FML]: Attempting connection with missing mods [mcp, FML, Forge, commands, permissions-commands, permissions, server-i18n, SkyEcon] at SERVER
212[20:46:01] [Server thread/TRACE] [FML/FML]: Sent event FMLConstructionEvent to mod FML
213[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - Forge Mod Loader took 0.312s
214[20:46:01] [Server thread/TRACE] [Forge/Forge]: Sending event FMLConstructionEvent to mod Forge
215[20:46:01] [Server thread/TRACE] [FML/Forge]: Mod Forge is using network checker : No network checking performed
216[20:46:01] [Server thread/TRACE] [FML/Forge]: Testing mod Forge to verify it accepts its own version in a remote connection
217[20:46:01] [Server thread/TRACE] [FML/Forge]: The mod Forge accepts its own version (12.16.0.1780)
218[20:46:01] [Server thread/TRACE] [Forge/Forge]: Sent event FMLConstructionEvent to mod Forge
219[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - Minecraft Forge took 0.007s
220[20:46:01] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLConstructionEvent to mod server-i18n
221[20:46:01] [Server thread/TRACE] [FML/server-i18n]: Mod server-i18n is using network checker : No network checking performed
222[20:46:01] [Server thread/TRACE] [FML/server-i18n]: Testing mod server-i18n to verify it accepts its own version in a remote connection
223[20:46:01] [Server thread/TRACE] [FML/server-i18n]: The mod server-i18n accepts its own version (1.0)
224[20:46:01] [Server thread/DEBUG] [FML/server-i18n]: Attempting to inject @SidedProxy classes into server-i18n
225[20:46:01] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLConstructionEvent to mod server-i18n
226[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - server-i18n took 0.016s
227[20:46:01] [Server thread/TRACE] [commands/commands]: Sending event FMLConstructionEvent to mod commands
228[20:46:01] [Server thread/TRACE] [FML/commands]: Mod commands is using network checker : No network checking performed
229[20:46:01] [Server thread/TRACE] [FML/commands]: Testing mod commands to verify it accepts its own version in a remote connection
230[20:46:01] [Server thread/TRACE] [FML/commands]: The mod commands accepts its own version (1.0)
231[20:46:01] [Server thread/DEBUG] [FML/commands]: Attempting to inject @SidedProxy classes into commands
232[20:46:01] [Server thread/TRACE] [commands/commands]: Sent event FMLConstructionEvent to mod commands
233[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - commands took 0.069s
234[20:46:01] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLConstructionEvent to mod permissions-commands
235[20:46:01] [Server thread/TRACE] [FML/permissions-commands]: Mod permissions-commands is using network checker : No network checking performed
236[20:46:01] [Server thread/TRACE] [FML/permissions-commands]: Testing mod permissions-commands to verify it accepts its own version in a remote connection
237[20:46:01] [Server thread/TRACE] [FML/permissions-commands]: The mod permissions-commands accepts its own version (1.0)
238[20:46:01] [Server thread/DEBUG] [FML/permissions-commands]: Attempting to inject @SidedProxy classes into permissions-commands
239[20:46:01] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLConstructionEvent to mod permissions-commands
240[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - permissions-commands took 0.005s
241[20:46:01] [Server thread/TRACE] [permissions/permissions]: Sending event FMLConstructionEvent to mod permissions
242[20:46:01] [Server thread/TRACE] [FML/permissions]: Mod permissions is using network checker : No network checking performed
243[20:46:01] [Server thread/TRACE] [FML/permissions]: Testing mod permissions to verify it accepts its own version in a remote connection
244[20:46:01] [Server thread/TRACE] [FML/permissions]: The mod permissions accepts its own version (1.0)
245[20:46:01] [Server thread/DEBUG] [FML/permissions]: Attempting to inject @SidedProxy classes into permissions
246[20:46:01] [Server thread/TRACE] [permissions/permissions]: Sent event FMLConstructionEvent to mod permissions
247[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - Permissions took 0.007s
248[20:46:01] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLConstructionEvent to mod SkyEcon
249[20:46:01] [Server thread/TRACE] [FML/SkyEcon]: Mod SkyEcon is using network checker : No network checking performed
250[20:46:01] [Server thread/TRACE] [FML/SkyEcon]: Testing mod SkyEcon to verify it accepts its own version in a remote connection
251[20:46:01] [Server thread/TRACE] [FML/SkyEcon]: The mod SkyEcon accepts its own version (1.1.3)
252[20:46:01] [Server thread/DEBUG] [FML/SkyEcon]: Attempting to inject @SidedProxy classes into SkyEcon
253[20:46:01] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLConstructionEvent to mod SkyEcon
254[20:46:01] [Server thread/DEBUG] [FML/]: Bar Step: Construction - SkyEcon took 0.011s
255[20:46:01] [Server thread/DEBUG] [FML/]: Bar Finished: Construction took 0.428s
256[20:46:01] [Server thread/DEBUG] [FML/]: Mod signature data
257[20:46:01] [Server thread/DEBUG] [FML/]:  	Valid Signatures:
258[20:46:01] [Server thread/DEBUG] [FML/]: 		(e3c3d50c7c986df74c645c0ac54639741c90a557) FML	(Forge Mod Loader	8.0.99.99)	forge-1.9-12.16.0.1780-1.9-universal.jar
259[20:46:01] [Server thread/DEBUG] [FML/]: 		(e3c3d50c7c986df74c645c0ac54639741c90a557) Forge	(Minecraft Forge	12.16.0.1780)	forge-1.9-12.16.0.1780-1.9-universal.jar
260[20:46:01] [Server thread/DEBUG] [FML/]:  	Missing Signatures:
261[20:46:01] [Server thread/DEBUG] [FML/]: 		mcp	(Minecraft Coder Pack	9.19)	minecraft.jar
262[20:46:01] [Server thread/DEBUG] [FML/]: 		server-i18n	(server-i18n	1.0)	server-i18n-1.0.1-2.jar
263[20:46:01] [Server thread/DEBUG] [FML/]: 		commands	(commands	1.0)	commands-foundation-1.0.0-4.jar
264[20:46:01] [Server thread/DEBUG] [FML/]: 		permissions-commands	(permissions-commands	1.0)	permissionforgecommands-1.0.0-4.jar
265[20:46:01] [Server thread/DEBUG] [FML/]: 		permissions	(Permissions	1.0)	permissionsforge-1.0.0-5.jar
266[20:46:01] [Server thread/DEBUG] [FML/]: 		SkyEcon	(SkyEcon	1.1.3)	skyecon-1.2.2.jar
267[20:46:01] [Server thread/INFO] [FML/]: Processing ObjectHolder annotations
268[20:46:01] [Server thread/INFO] [FML/]: Found 418 ObjectHolder annotations
269[20:46:01] [Server thread/INFO] [FML/]: Identifying ItemStackHolder annotations
270[20:46:01] [Server thread/INFO] [FML/]: Found 0 ItemStackHolder annotations
271[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sending event FMLPreInitializationEvent to mod mcp
272[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sent event FMLPreInitializationEvent to mod mcp
273[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - Minecraft Coder Pack took 0.000s
274[20:46:02] [Server thread/TRACE] [FML/FML]: Sending event FMLPreInitializationEvent to mod FML
275[20:46:02] [Server thread/TRACE] [FML/FML]: Sent event FMLPreInitializationEvent to mod FML
276[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - Forge Mod Loader took 0.012s
277[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sending event FMLPreInitializationEvent to mod Forge
278[20:46:02] [Server thread/INFO] [FML/Forge]: Configured a dormant chunk cache size of 0
279[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sent event FMLPreInitializationEvent to mod Forge
280[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - Minecraft Forge took 0.042s
281[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLPreInitializationEvent to mod server-i18n
282[20:46:02] [Forge Version Check/INFO] [ForgeVersionCheck/Forge]: [Forge] Starting version check at http://files.minecraftforge.net/maven/net/minecraftforge/forge/promotions_slim.json
283[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLPreInitializationEvent to mod server-i18n
284[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - server-i18n took 0.023s
285[20:46:02] [Server thread/TRACE] [commands/commands]: Sending event FMLPreInitializationEvent to mod commands
286[20:46:02] [Server thread/TRACE] [commands/commands]: Sent event FMLPreInitializationEvent to mod commands
287[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - commands took 0.008s
288[20:46:02] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLPreInitializationEvent to mod permissions-commands
289[20:46:02] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLPreInitializationEvent to mod permissions-commands
290[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - permissions-commands took 0.000s
291[20:46:02] [Server thread/TRACE] [permissions/permissions]: Sending event FMLPreInitializationEvent to mod permissions
292[20:46:02] [Forge Version Check/DEBUG] [ForgeVersionCheck/Forge]: [Forge] Received version check data:
293{
294  "homepage": "http://files.minecraftforge.net/maven/net/minecraftforge/forge/",
295  "promos": {
296    "1.5.2-latest": "7.8.1.738",
297    "1.5.2-recommended": "7.8.1.737",
298    "1.6.1-latest": "8.9.0.775",
299    "1.6.2-latest": "9.10.1.871",
300    "1.6.2-recommended": "9.10.1.871",
301    "1.6.3-latest": "9.11.0.878",
302    "1.6.4-latest": "9.11.1.1345",
303    "1.6.4-recommended": "9.11.1.1345",
304    "1.7.10-latest": "10.13.4.1614",
305    "1.7.10-latest-1.7.10": "10.13.2.1343",
306    "1.7.10-recommended": "10.13.4.1558",
307    "1.7.2-latest": "10.12.2.1147",
308    "1.7.2-recommended": "10.12.2.1121",
309    "1.8-latest": "11.14.4.1577",
310    "1.8-recommended": "11.14.4.1563",
311    "1.8.8-latest": "11.15.0.1655",
312    "1.8.9-latest": "11.15.1.1808",
313    "1.8.9-recommended": "11.15.1.1722",
314    "1.9-latest": "12.16.0.1811",
315    "latest": "11.15.1.1808",
316    "latest-1.7.10": "10.13.2.1343",
317    "recommended": "11.15.1.1722"
318  }
319}
320[20:46:02] [Forge Version Check/INFO] [ForgeVersionCheck/Forge]: [Forge] Found status: BETA_OUTDATED Target: 12.16.0.1811
321[20:46:02] [Server thread/DEBUG] [com.j256.ormlite.dao.DaoManager/permissions]: created dao for class class ru.skymine.permissions.entity.Group with reflection
322[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: creating table 'groups'
323[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection opened: com.mysql.jdbc.JDBC4Connection@49a4ae39
324[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection set autoCommit to true
325[20:46:02] [Server thread/DEBUG] [com.j256.ormlite.jdbc.JdbcConnectionSource/permissions]: opened connection to jdbc:mysql://91.121.72.61:3306/economy?user=root&password=t818CrXR7jPKDRq got #1276262519
326[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: CREATE TABLE IF NOT EXISTS `groups` (`group` VARCHAR(255) , `parent_id` VARCHAR(255) , `rank` INTEGER , `prefix` VARCHAR(255) , `suffix` VARCHAR(255) , `worlds` TEXT , `default` TINYINT(1) , PRIMARY KEY (`group`) ) ENGINE=InnoDB 
327[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: executed create table statement changed 0 rows: CREATE TABLE IF NOT EXISTS `groups` (`group` VARCHAR(255) , `parent_id` VARCHAR(255) , `rank` INTEGER , `prefix` VARCHAR(255) , `suffix` VARCHAR(255) , `worlds` TEXT , `default` TINYINT(1) , PRIMARY KEY (`group`) ) ENGINE=InnoDB 
328[20:46:02] [Server thread/DEBUG] [com.j256.ormlite.dao.DaoManager/permissions]: created dao for class class ru.skymine.permissions.entity.GroupPermission with reflection
329[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: creating table 'permissions_groups'
330[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
331[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: CREATE TABLE IF NOT EXISTS `permissions_groups` (`id` INTEGER AUTO_INCREMENT , `group` VARCHAR(255) NOT NULL , `permission` VARCHAR(255) NOT NULL , `worlds` TEXT , PRIMARY KEY (`id`) ) ENGINE=InnoDB 
332[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: executed create table statement changed 0 rows: CREATE TABLE IF NOT EXISTS `permissions_groups` (`id` INTEGER AUTO_INCREMENT , `group` VARCHAR(255) NOT NULL , `permission` VARCHAR(255) NOT NULL , `worlds` TEXT , PRIMARY KEY (`id`) ) ENGINE=InnoDB 
333[20:46:02] [Server thread/DEBUG] [com.j256.ormlite.dao.DaoManager/permissions]: created dao for class class ru.skymine.permissions.entity.UserPermission with reflection
334[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: creating table 'permissions_users'
335[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
336[20:46:02] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: CREATE TABLE IF NOT EXISTS `permissions_users` (`id` INTEGER AUTO_INCREMENT , `username` VARCHAR(255) NOT NULL , `group` VARCHAR(255) , `permission` VARCHAR(255) , `prefix` VARCHAR(255) , `suffix` VARCHAR(255) , `start_date` BIGINT NOT NULL , `expiration_date` BIGINT NOT NULL , PRIMARY KEY (`id`) ) ENGINE=InnoDB 
337[20:46:02] [Server thread/INFO] [com.j256.ormlite.table.TableUtils/permissions]: executed create table statement changed 0 rows: CREATE TABLE IF NOT EXISTS `permissions_users` (`id` INTEGER AUTO_INCREMENT , `username` VARCHAR(255) NOT NULL , `group` VARCHAR(255) , `permission` VARCHAR(255) , `prefix` VARCHAR(255) , `suffix` VARCHAR(255) , `start_date` BIGINT NOT NULL , `expiration_date` BIGINT NOT NULL , PRIMARY KEY (`id`) ) ENGINE=InnoDB 
338[20:46:02] [Server thread/TRACE] [permissions/permissions]: Sent event FMLPreInitializationEvent to mod permissions
339[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - Permissions took 0.589s
340[20:46:02] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLPreInitializationEvent to mod SkyEcon
341[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementBuilder/permissions]: built statement SELECT * FROM `groups` 
342[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
343[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
344[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
345[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1738693832 for 'SELECT * FROM `groups` '
346[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1738693832 after 0 rows
347[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
348[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementBuilder/permissions]: built statement SELECT * FROM `permissions_groups` 
349[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
350[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
351[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
352[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1286434596 for 'SELECT * FROM `permissions_groups` '
353[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1286434596 after 0 rows
354[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
355[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementBuilder/permissions]: built statement SELECT * FROM `permissions_users` 
356[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
357[20:46:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
358[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
359[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1653633496 for 'SELECT * FROM `permissions_users` '
360[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1653633496 after 2 rows
361[20:46:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 2 results
362[20:46:02] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLPreInitializationEvent to mod SkyEcon
363[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: PreInitialization - SkyEcon took 0.169s
364[20:46:02] [Server thread/DEBUG] [FML/]: Bar Finished: PreInitialization took 0.844s
365[20:46:02] [Server thread/INFO] [FML/]: Applying holder lookups
366[20:46:02] [Server thread/INFO] [FML/]: Holder lookups applied
367[20:46:02] [Server thread/INFO] [FML/]: Injecting itemstacks
368[20:46:02] [Server thread/INFO] [FML/]: Itemstack injection complete
369[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sending event FMLInitializationEvent to mod mcp
370[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sent event FMLInitializationEvent to mod mcp
371[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - Minecraft Coder Pack took 0.000s
372[20:46:02] [Server thread/TRACE] [FML/FML]: Sending event FMLInitializationEvent to mod FML
373[20:46:02] [Server thread/TRACE] [FML/FML]: Sent event FMLInitializationEvent to mod FML
374[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - Forge Mod Loader took 0.000s
375[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sending event FMLInitializationEvent to mod Forge
376[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sent event FMLInitializationEvent to mod Forge
377[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - Minecraft Forge took 0.000s
378[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLInitializationEvent to mod server-i18n
379[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLInitializationEvent to mod server-i18n
380[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - server-i18n took 0.001s
381[20:46:02] [Server thread/TRACE] [commands/commands]: Sending event FMLInitializationEvent to mod commands
382[20:46:02] [Server thread/TRACE] [commands/commands]: Sent event FMLInitializationEvent to mod commands
383[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - commands took 0.000s
384[20:46:02] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLInitializationEvent to mod permissions-commands
385[20:46:02] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLInitializationEvent to mod permissions-commands
386[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - permissions-commands took 0.000s
387[20:46:02] [Server thread/TRACE] [permissions/permissions]: Sending event FMLInitializationEvent to mod permissions
388[20:46:02] [Server thread/TRACE] [permissions/permissions]: Sent event FMLInitializationEvent to mod permissions
389[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - Permissions took 0.000s
390[20:46:02] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLInitializationEvent to mod SkyEcon
391[20:46:02] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLInitializationEvent to mod SkyEcon
392[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: Initialization - SkyEcon took 0.000s
393[20:46:02] [Server thread/DEBUG] [FML/]: Bar Finished: Initialization took 0.003s
394[20:46:02] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod mcp
395[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sending event IMCEvent to mod mcp
396[20:46:02] [Server thread/TRACE] [mcp/mcp]: Sent event IMCEvent to mod mcp
397[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - Minecraft Coder Pack took 0.004s
398[20:46:02] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod FML
399[20:46:02] [Server thread/TRACE] [FML/FML]: Sending event IMCEvent to mod FML
400[20:46:02] [Server thread/TRACE] [FML/FML]: Sent event IMCEvent to mod FML
401[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - Forge Mod Loader took 0.000s
402[20:46:02] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod Forge
403[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sending event IMCEvent to mod Forge
404[20:46:02] [Server thread/TRACE] [Forge/Forge]: Sent event IMCEvent to mod Forge
405[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - Minecraft Forge took 0.000s
406[20:46:02] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod server-i18n
407[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event IMCEvent to mod server-i18n
408[20:46:02] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event IMCEvent to mod server-i18n
409[20:46:02] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - server-i18n took 0.000s
410[20:46:03] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod commands
411[20:46:03] [Server thread/TRACE] [commands/commands]: Sending event IMCEvent to mod commands
412[20:46:03] [Server thread/TRACE] [commands/commands]: Sent event IMCEvent to mod commands
413[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - commands took 0.000s
414[20:46:03] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod permissions-commands
415[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event IMCEvent to mod permissions-commands
416[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event IMCEvent to mod permissions-commands
417[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - permissions-commands took 0.000s
418[20:46:03] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod permissions
419[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sending event IMCEvent to mod permissions
420[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sent event IMCEvent to mod permissions
421[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - Permissions took 0.000s
422[20:46:03] [Server thread/TRACE] [FML/]: Attempting to deliver 0 IMC messages to mod SkyEcon
423[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event IMCEvent to mod SkyEcon
424[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event IMCEvent to mod SkyEcon
425[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: InterModComms$IMC - SkyEcon took 0.000s
426[20:46:03] [Server thread/DEBUG] [FML/]: Bar Finished: InterModComms$IMC took 0.007s
427[20:46:03] [Server thread/INFO] [FML/]: Injecting itemstacks
428[20:46:03] [Server thread/INFO] [FML/]: Itemstack injection complete
429[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sending event FMLPostInitializationEvent to mod mcp
430[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sent event FMLPostInitializationEvent to mod mcp
431[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - Minecraft Coder Pack took 0.000s
432[20:46:03] [Server thread/TRACE] [FML/FML]: Sending event FMLPostInitializationEvent to mod FML
433[20:46:03] [Server thread/TRACE] [FML/FML]: Sent event FMLPostInitializationEvent to mod FML
434[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - Forge Mod Loader took 0.000s
435[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sending event FMLPostInitializationEvent to mod Forge
436[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sent event FMLPostInitializationEvent to mod Forge
437[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - Minecraft Forge took 0.009s
438[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLPostInitializationEvent to mod server-i18n
439[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLPostInitializationEvent to mod server-i18n
440[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - server-i18n took 0.000s
441[20:46:03] [Server thread/TRACE] [commands/commands]: Sending event FMLPostInitializationEvent to mod commands
442[20:46:03] [Server thread/TRACE] [commands/commands]: Sent event FMLPostInitializationEvent to mod commands
443[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - commands took 0.045s
444[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLPostInitializationEvent to mod permissions-commands
445[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLPostInitializationEvent to mod permissions-commands
446[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - permissions-commands took 0.000s
447[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sending event FMLPostInitializationEvent to mod permissions
448[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sent event FMLPostInitializationEvent to mod permissions
449[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - Permissions took 0.000s
450[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLPostInitializationEvent to mod SkyEcon
451[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLPostInitializationEvent to mod SkyEcon
452[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: PostInitialization - SkyEcon took 0.000s
453[20:46:03] [Server thread/DEBUG] [FML/]: Bar Finished: PostInitialization took 0.055s
454[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sending event FMLLoadCompleteEvent to mod mcp
455[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sent event FMLLoadCompleteEvent to mod mcp
456[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - Minecraft Coder Pack took 0.000s
457[20:46:03] [Server thread/TRACE] [FML/FML]: Sending event FMLLoadCompleteEvent to mod FML
458[20:46:03] [Server thread/TRACE] [FML/FML]: Sent event FMLLoadCompleteEvent to mod FML
459[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - Forge Mod Loader took 0.000s
460[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sending event FMLLoadCompleteEvent to mod Forge
461[20:46:03] [Server thread/DEBUG] [FML/Forge]: Forge RecipeSorter Baking:
462[20:46:03] [Server thread/DEBUG] [FML/Forge]:   14: RecipeEntry("Before", UNKNOWN, )
463[20:46:03] [Server thread/DEBUG] [FML/Forge]:   13: RecipeEntry("minecraft:shaped", SHAPED, net.minecraft.item.crafting.ShapedRecipes) Before: minecraft:shapeless
464[20:46:03] [Server thread/DEBUG] [FML/Forge]:   12: RecipeEntry("forge:shapedore", SHAPED, net.minecraftforge.oredict.ShapedOreRecipe) Before: minecraft:shapeless After: minecraft:shaped
465[20:46:03] [Server thread/DEBUG] [FML/Forge]:   11: RecipeEntry("minecraft:mapextending", SHAPED, net.minecraft.item.crafting.RecipesMapExtending) Before: minecraft:shapeless After: minecraft:shaped
466[20:46:03] [Server thread/DEBUG] [FML/Forge]:   10: RecipeEntry("minecraft:shapeless", SHAPELESS, net.minecraft.item.crafting.ShapelessRecipes) After: minecraft:shaped
467[20:46:03] [Server thread/DEBUG] [FML/Forge]:   9: RecipeEntry("minecraft:repair", SHAPELESS, net.minecraft.item.crafting.RecipeRepairItem) After: minecraft:shapeless
468[20:46:03] [Server thread/DEBUG] [FML/Forge]:   8: RecipeEntry("forge:shapelessore", SHAPELESS, net.minecraftforge.oredict.ShapelessOreRecipe) After: minecraft:shapeless
469[20:46:03] [Server thread/DEBUG] [FML/Forge]:   7: RecipeEntry("minecraft:armordyes", SHAPELESS, net.minecraft.item.crafting.RecipesArmorDyes) After: minecraft:shapeless
470[20:46:03] [Server thread/DEBUG] [FML/Forge]:   6: RecipeEntry("minecraft:fireworks", SHAPELESS, net.minecraft.item.crafting.RecipeFireworks) After: minecraft:shapeless
471[20:46:03] [Server thread/DEBUG] [FML/Forge]:   5: RecipeEntry("minecraft:pattern_add", SHAPELESS, net.minecraft.item.crafting.RecipesBanners$RecipeAddPattern) After: minecraft:shapeless
472[20:46:03] [Server thread/DEBUG] [FML/Forge]:   4: RecipeEntry("minecraft:pattern_dupe", SHAPELESS, net.minecraft.item.crafting.RecipesBanners$RecipeDuplicatePattern) After: minecraft:shapeless
473[20:46:03] [Server thread/DEBUG] [FML/Forge]:   3: RecipeEntry("minecraft:bookcloning", SHAPELESS, net.minecraft.item.crafting.RecipeBookCloning) After: minecraft:shapeless
474[20:46:03] [Server thread/DEBUG] [FML/Forge]:   2: RecipeEntry("minecraft:mapcloning", SHAPELESS, net.minecraft.item.crafting.RecipesMapCloning) After: minecraft:shapeless
475[20:46:03] [Server thread/DEBUG] [FML/Forge]:   1: RecipeEntry("After", UNKNOWN, )
476[20:46:03] [Server thread/DEBUG] [FML/Forge]: Sorting recipies
477[20:46:03] [Server thread/INFO] [FML/Forge]:   Unknown recipe class! net.minecraft.item.crafting.RecipeTippedArrow Modder please refer to net.minecraftforge.oredict.RecipeSorter
478[20:46:03] [Server thread/INFO] [FML/Forge]:   Unknown recipe class! net.minecraft.item.crafting.ShieldRecipes$Decoration Modder please refer to net.minecraftforge.oredict.RecipeSorter
479[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sent event FMLLoadCompleteEvent to mod Forge
480[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - Minecraft Forge took 0.009s
481[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLLoadCompleteEvent to mod server-i18n
482[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLLoadCompleteEvent to mod server-i18n
483[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - server-i18n took 0.000s
484[20:46:03] [Server thread/TRACE] [commands/commands]: Sending event FMLLoadCompleteEvent to mod commands
485[20:46:03] [Server thread/TRACE] [commands/commands]: Sent event FMLLoadCompleteEvent to mod commands
486[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - commands took 0.000s
487[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLLoadCompleteEvent to mod permissions-commands
488[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLLoadCompleteEvent to mod permissions-commands
489[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - permissions-commands took 0.000s
490[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sending event FMLLoadCompleteEvent to mod permissions
491[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sent event FMLLoadCompleteEvent to mod permissions
492[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - Permissions took 0.000s
493[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLLoadCompleteEvent to mod SkyEcon
494[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLLoadCompleteEvent to mod SkyEcon
495[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: LoadComplete - SkyEcon took 0.000s
496[20:46:03] [Server thread/DEBUG] [FML/]: Bar Finished: LoadComplete took 0.010s
497[20:46:03] [Server thread/DEBUG] [FML/]: Freezing block and item id maps
498[20:46:03] [Server thread/INFO] [FML/]: Forge Mod Loader has successfully loaded 8 mods
499[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sending event FMLServerAboutToStartEvent to mod mcp
500[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sent event FMLServerAboutToStartEvent to mod mcp
501[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - Minecraft Coder Pack took 0.000s
502[20:46:03] [Server thread/TRACE] [FML/FML]: Sending event FMLServerAboutToStartEvent to mod FML
503[20:46:03] [Server thread/TRACE] [FML/FML]: Sent event FMLServerAboutToStartEvent to mod FML
504[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - Forge Mod Loader took 0.000s
505[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sending event FMLServerAboutToStartEvent to mod Forge
506[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sent event FMLServerAboutToStartEvent to mod Forge
507[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - Minecraft Forge took 0.000s
508[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLServerAboutToStartEvent to mod server-i18n
509[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLServerAboutToStartEvent to mod server-i18n
510[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - server-i18n took 0.000s
511[20:46:03] [Server thread/TRACE] [commands/commands]: Sending event FMLServerAboutToStartEvent to mod commands
512[20:46:03] [Server thread/TRACE] [commands/commands]: Sent event FMLServerAboutToStartEvent to mod commands
513[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - commands took 0.000s
514[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLServerAboutToStartEvent to mod permissions-commands
515[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLServerAboutToStartEvent to mod permissions-commands
516[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - permissions-commands took 0.000s
517[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sending event FMLServerAboutToStartEvent to mod permissions
518[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sent event FMLServerAboutToStartEvent to mod permissions
519[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - Permissions took 0.000s
520[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLServerAboutToStartEvent to mod SkyEcon
521[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLServerAboutToStartEvent to mod SkyEcon
522[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ServerAboutToStart - SkyEcon took 0.000s
523[20:46:03] [Server thread/DEBUG] [FML/]: Bar Finished: ServerAboutToStart took 0.002s
524[20:46:03] [Server thread/INFO] [FML/]: Injecting existing block and item data into this server instance
525[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sending event FMLModIdMappingEvent to mod mcp
526[20:46:03] [Server thread/TRACE] [mcp/mcp]: Sent event FMLModIdMappingEvent to mod mcp
527[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - Minecraft Coder Pack took 0.000s
528[20:46:03] [Server thread/TRACE] [FML/FML]: Sending event FMLModIdMappingEvent to mod FML
529[20:46:03] [Server thread/TRACE] [FML/FML]: Sent event FMLModIdMappingEvent to mod FML
530[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - Forge Mod Loader took 0.000s
531[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sending event FMLModIdMappingEvent to mod Forge
532[20:46:03] [Server thread/TRACE] [Forge/Forge]: Sent event FMLModIdMappingEvent to mod Forge
533[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - Minecraft Forge took 0.000s
534[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLModIdMappingEvent to mod server-i18n
535[20:46:03] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLModIdMappingEvent to mod server-i18n
536[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - server-i18n took 0.000s
537[20:46:03] [Server thread/TRACE] [commands/commands]: Sending event FMLModIdMappingEvent to mod commands
538[20:46:03] [Server thread/TRACE] [commands/commands]: Sent event FMLModIdMappingEvent to mod commands
539[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - commands took 0.000s
540[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLModIdMappingEvent to mod permissions-commands
541[20:46:03] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLModIdMappingEvent to mod permissions-commands
542[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - permissions-commands took 0.000s
543[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sending event FMLModIdMappingEvent to mod permissions
544[20:46:03] [Server thread/TRACE] [permissions/permissions]: Sent event FMLModIdMappingEvent to mod permissions
545[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - Permissions took 0.000s
546[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLModIdMappingEvent to mod SkyEcon
547[20:46:03] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLModIdMappingEvent to mod SkyEcon
548[20:46:03] [Server thread/DEBUG] [FML/]: Bar Step: ModIdMapping - SkyEcon took 0.000s
549[20:46:03] [Server thread/DEBUG] [FML/]: Bar Finished: ModIdMapping took 0.002s
550[20:46:03] [Server thread/INFO] [FML/]: Applying holder lookups
551[20:46:03] [Server thread/INFO] [FML/]: Holder lookups applied
552[20:46:03] [Server thread/DEBUG] [FML/]: Loading persistent fluid defaults from world
553[20:46:03] [Server thread/DEBUG] [FML/]: The fluid minecraft:lava has been selected as the default fluid for lava
554[20:46:03] [Server thread/DEBUG] [FML/]: The fluid minecraft:water has been selected as the default fluid for water
555[20:46:03] [Server thread/INFO] [FML/]: Loading dimension 0 (world) (net.minecraft.server.dedicated.DedicatedServer@63720846)
556[20:46:03] [Server thread/INFO] [FML/]: Loading dimension 1 (world) (net.minecraft.server.dedicated.DedicatedServer@63720846)
557[20:46:03] [Server thread/INFO] [FML/]: Loading dimension -1 (world) (net.minecraft.server.dedicated.DedicatedServer@63720846)
558[20:46:04] [Server thread/TRACE] [mcp/mcp]: Sending event FMLServerStartingEvent to mod mcp
559[20:46:04] [Server thread/TRACE] [mcp/mcp]: Sent event FMLServerStartingEvent to mod mcp
560[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - Minecraft Coder Pack took 0.000s
561[20:46:04] [Server thread/TRACE] [FML/FML]: Sending event FMLServerStartingEvent to mod FML
562[20:46:04] [Server thread/TRACE] [FML/FML]: Sent event FMLServerStartingEvent to mod FML
563[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - Forge Mod Loader took 0.000s
564[20:46:04] [Server thread/TRACE] [Forge/Forge]: Sending event FMLServerStartingEvent to mod Forge
565[20:46:04] [Server thread/TRACE] [Forge/Forge]: Sent event FMLServerStartingEvent to mod Forge
566[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - Minecraft Forge took 0.003s
567[20:46:04] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLServerStartingEvent to mod server-i18n
568[20:46:04] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLServerStartingEvent to mod server-i18n
569[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - server-i18n took 0.000s
570[20:46:04] [Server thread/TRACE] [commands/commands]: Sending event FMLServerStartingEvent to mod commands
571[20:46:04] [Server thread/TRACE] [commands/commands]: Sent event FMLServerStartingEvent to mod commands
572[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - commands took 0.022s
573[20:46:04] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLServerStartingEvent to mod permissions-commands
574[20:46:04] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLServerStartingEvent to mod permissions-commands
575[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - permissions-commands took 0.001s
576[20:46:04] [Server thread/TRACE] [permissions/permissions]: Sending event FMLServerStartingEvent to mod permissions
577[20:46:04] [Server thread/TRACE] [permissions/permissions]: Sent event FMLServerStartingEvent to mod permissions
578[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - Permissions took 0.000s
579[20:46:04] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLServerStartingEvent to mod SkyEcon
580[20:46:04] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLServerStartingEvent to mod SkyEcon
581[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarting - SkyEcon took 0.000s
582[20:46:04] [Server thread/DEBUG] [FML/]: Bar Finished: ServerStarting took 0.027s
583[20:46:04] [Server thread/TRACE] [mcp/mcp]: Sending event FMLServerStartedEvent to mod mcp
584[20:46:04] [Server thread/TRACE] [mcp/mcp]: Sent event FMLServerStartedEvent to mod mcp
585[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - Minecraft Coder Pack took 0.000s
586[20:46:04] [Server thread/TRACE] [FML/FML]: Sending event FMLServerStartedEvent to mod FML
587[20:46:04] [Server thread/TRACE] [FML/FML]: Sent event FMLServerStartedEvent to mod FML
588[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - Forge Mod Loader took 0.000s
589[20:46:04] [Server thread/TRACE] [Forge/Forge]: Sending event FMLServerStartedEvent to mod Forge
590[20:46:04] [Server thread/TRACE] [Forge/Forge]: Sent event FMLServerStartedEvent to mod Forge
591[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - Minecraft Forge took 0.000s
592[20:46:04] [Server thread/TRACE] [server-i18n/server-i18n]: Sending event FMLServerStartedEvent to mod server-i18n
593[20:46:04] [Server thread/TRACE] [server-i18n/server-i18n]: Sent event FMLServerStartedEvent to mod server-i18n
594[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - server-i18n took 0.000s
595[20:46:04] [Server thread/TRACE] [commands/commands]: Sending event FMLServerStartedEvent to mod commands
596[20:46:04] [Server thread/TRACE] [commands/commands]: Sent event FMLServerStartedEvent to mod commands
597[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - commands took 0.000s
598[20:46:04] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sending event FMLServerStartedEvent to mod permissions-commands
599[20:46:04] [Server thread/TRACE] [permissions-commands/permissions-commands]: Sent event FMLServerStartedEvent to mod permissions-commands
600[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - permissions-commands took 0.000s
601[20:46:04] [Server thread/TRACE] [permissions/permissions]: Sending event FMLServerStartedEvent to mod permissions
602[20:46:04] [Server thread/TRACE] [permissions/permissions]: Sent event FMLServerStartedEvent to mod permissions
603[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - Permissions took 0.000s
604[20:46:04] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sending event FMLServerStartedEvent to mod SkyEcon
605[20:46:04] [Server thread/TRACE] [SkyEcon/SkyEcon]: Sent event FMLServerStartedEvent to mod SkyEcon
606[20:46:04] [Server thread/DEBUG] [FML/]: Bar Step: ServerStarted - SkyEcon took 0.000s
607[20:46:04] [Server thread/DEBUG] [FML/]: Bar Finished: ServerStarted took 0.001s
608[20:46:08] [Netty Epoll Server IO #1/TRACE] [FML/]: Handshake channel activating
609[20:46:08] [Netty Epoll Server IO #1/DEBUG] [FML/]: FMLHandshakeServerState: null->FMLHandshakeServerState$1:START
610[20:46:08] [Server thread/INFO] [FML/]: Connection received without FML marker, assuming vanilla.
611[20:46:08] [Netty Epoll Server IO #1/DEBUG] [FML/]:   Next: HELLO
612[20:46:08] [Server thread/INFO] [FML/]: [Server thread] Server side vanilla connection established
613[20:46:49] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
614[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
615[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
616[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
617[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1551989720 for 'SELECT * FROM `groups` '
618[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1551989720 after 0 rows
619[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
620[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
621[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
622[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
623[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @110432085 for 'SELECT * FROM `permissions_groups` '
624[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @110432085 after 0 rows
625[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
626[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
627[20:47:02] [pool-2-thread-1/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
628[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
629[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1747811580 for 'SELECT * FROM `permissions_users` '
630[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1747811580 after 2 rows
631[20:47:02] [pool-2-thread-1/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 2 results
632[20:47:20] [Netty Epoll Server IO #2/TRACE] [FML/]: Handshake channel activating
633[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: null->FMLHandshakeServerState$1:START
634[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: HELLO
635[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $ClientHello->FMLHandshakeServerState$2:HELLO
636[20:47:20] [Netty Epoll Server IO #2/INFO] [FML/]: Client protocol version 2
637[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: HELLO
638[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $ModList:6 mods->FMLHandshakeServerState$2:HELLO
639[20:47:20] [Netty Epoll Server IO #2/INFO] [FML/]: Client attempting to join with 6 mods : FML@8.0.99.99,permissions@1.0,Forge@12.16.0.1770,server-i18n@1.0,mcp@9.19,commands@1.0
640[20:47:20] [Netty Epoll Server IO #2/INFO] [FML/]: Attempting connection with missing mods [permissions-commands, SkyEcon] at CLIENT
641[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: WAITINGCACK
642[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{2}->FMLHandshakeServerState$3:WAITINGCACK
643[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: COMPLETE
644[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{3}->FMLHandshakeServerState$4:COMPLETE
645[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: DONE
646[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{4}->FMLHandshakeServerState$5:DONE
647[20:47:20] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: DONE
648[20:47:20] [Server thread/INFO] [FML/]: [Server thread] Server side modded connection established
649[20:47:21] [Netty Epoll Server IO #2/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{5}->FMLHandshakeServerState$5:DONE
650[20:47:21] [Netty Epoll Server IO #2/DEBUG] [FML/]:   Next: DONE
651[20:47:34] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
652[20:47:36] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
653[20:47:36] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: insert statement is prepared and executed: INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)
654[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert data with statement 'INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)' and 7 args, changed 1 rows
655[20:47:36] [Server thread/TRACE] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert arguments: [FineUser, null, ru.*, null, null, 1459104456452, -1]
656[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: assigned id '4' from keyholder to 'id' in UserPermission object
657[20:47:36] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
658[20:47:36] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: compiled statement: SELECT * FROM `permissions_users` 
659[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
660[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: starting iterator @3097619 for 'SELECT * FROM `permissions_users` '
661[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: closed iterator @3097619 after 3 rows
662[20:47:36] [Server thread/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/]: query of 'SELECT * FROM `permissions_users` ' returned 3 results
663[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
664[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
665[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
666[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @456936672 for 'SELECT * FROM `groups` '
667[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @456936672 after 0 rows
668[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
669[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
670[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
671[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
672[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1510033115 for 'SELECT * FROM `permissions_groups` '
673[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1510033115 after 0 rows
674[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
675[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
676[20:48:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
677[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
678[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1804728966 for 'SELECT * FROM `permissions_users` '
679[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1804728966 after 3 rows
680[20:48:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 3 results
681[20:48:16] [Netty Epoll Server IO #4/TRACE] [FML/]: Handshake channel activating
682[20:48:16] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: null->FMLHandshakeServerState$1:START
683[20:48:16] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: HELLO
684[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $ClientHello->FMLHandshakeServerState$2:HELLO
685[20:48:17] [Netty Epoll Server IO #4/INFO] [FML/]: Client protocol version 2
686[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: HELLO
687[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $ModList:7 mods->FMLHandshakeServerState$2:HELLO
688[20:48:17] [Netty Epoll Server IO #4/INFO] [FML/]: Client attempting to join with 7 mods : FML@8.0.99.99,permissions@1.0,Forge@12.16.0.1770,server-i18n@1.0,permissions-commands@1.0,mcp@9.19,SkyEcon@1.1.3
689[20:48:17] [Netty Epoll Server IO #4/INFO] [FML/]: Attempting connection with missing mods [commands] at CLIENT
690[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: WAITINGCACK
691[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{2}->FMLHandshakeServerState$3:WAITINGCACK
692[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: COMPLETE
693[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{3}->FMLHandshakeServerState$4:COMPLETE
694[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: DONE
695[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{4}->FMLHandshakeServerState$5:DONE
696[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: DONE
697[20:48:17] [Server thread/INFO] [FML/]: [Server thread] Server side modded connection established
698[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{5}->FMLHandshakeServerState$5:DONE
699[20:48:17] [Netty Epoll Server IO #4/DEBUG] [FML/]:   Next: DONE
700[20:48:19] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
701[20:48:20] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
702[20:48:20] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: insert statement is prepared and executed: INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)
703[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert data with statement 'INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)' and 7 args, changed 1 rows
704[20:48:20] [Server thread/TRACE] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert arguments: [FineUser, null, *, null, null, 1459104500184, -1]
705[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: assigned id '5' from keyholder to 'id' in UserPermission object
706[20:48:20] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
707[20:48:20] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: compiled statement: SELECT * FROM `permissions_users` 
708[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
709[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: starting iterator @1599278329 for 'SELECT * FROM `permissions_users` '
710[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: closed iterator @1599278329 after 4 rows
711[20:48:20] [Server thread/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/]: query of 'SELECT * FROM `permissions_users` ' returned 4 results
712[20:48:55] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
713[20:48:55] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: insert statement is prepared and executed: INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)
714[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert data with statement 'INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)' and 7 args, changed 1 rows
715[20:48:55] [Server thread/TRACE] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert arguments: [RedEnergy, null, *, null, null, 1459104535207, -1]
716[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: assigned id '6' from keyholder to 'id' in UserPermission object
717[20:48:55] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
718[20:48:55] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: compiled statement: SELECT * FROM `permissions_users` 
719[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
720[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: starting iterator @820687876 for 'SELECT * FROM `permissions_users` '
721[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: closed iterator @820687876 after 5 rows
722[20:48:55] [Server thread/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/]: query of 'SELECT * FROM `permissions_users` ' returned 5 results
723[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
724[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
725[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
726[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @976140692 for 'SELECT * FROM `groups` '
727[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @976140692 after 0 rows
728[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
729[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
730[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
731[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
732[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1405804547 for 'SELECT * FROM `permissions_groups` '
733[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1405804547 after 0 rows
734[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
735[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
736[20:49:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
737[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
738[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1046537995 for 'SELECT * FROM `permissions_users` '
739[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1046537995 after 5 rows
740[20:49:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 5 results
741[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: java.lang.reflect.InvocationTargetException
742[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
743[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
744[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
745[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.reflect.Method.invoke(Method.java:498)
746[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.invokeCommand(CommandSet.java:73)
747[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.resolveAndInvoke(CommandSet.java:60)
748[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.func_184881_a(CommandSet.java:147)
749[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_175786_a(CommandHandler.java:102)
750[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_71556_a(CommandHandler.java:81)
751[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147361_d(NetHandlerPlayServer.java:916)
752[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147354_a(NetHandlerPlayServer.java:895)
753[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:37)
754[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:9)
755[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.PacketThreadUtil$1.run(SourceFile:13)
756[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
757[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
758[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.util.Util.func_181617_a(SourceFile:45)
759[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71190_q(MinecraftServer.java:664)
760[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.dedicated.DedicatedServer.func_71190_q(DedicatedServer.java:386)
761[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71217_p(MinecraftServer.java:609)
762[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.run(MinecraftServer.java:467)
763[20:49:04] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.Thread.run(Thread.java:745)
764[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: Caused by: java.lang.NullPointerException
765[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.world.Teleporter.func_180620_b(SourceFile:127)
766[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.world.Teleporter.func_180266_a(SourceFile:65)
767[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.server.management.PlayerList.transferEntityToWorld(PlayerList.java:695)
768[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.server.management.PlayerList.transferPlayerToDimension(PlayerList.java:605)
769[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.server.management.PlayerList.func_187242_a(PlayerList.java:592)
770[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at net.minecraft.entity.player.EntityPlayerMP.func_184204_a(EntityPlayerMP.java:594)
771[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at ru.skymine.commands.set.tp.Tp.transferPlayer(Tp.java:59)
772[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	at ru.skymine.commands.set.tp.Tp.tp(Tp.java:54)
773[20:49:04] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: 	... 22 more
774[20:49:04] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
775[20:49:04] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: insert statement is prepared and executed: INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)
776[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert data with statement 'INSERT INTO `permissions_users` (`username` ,`group` ,`permission` ,`prefix` ,`suffix` ,`start_date` ,`expiration_date` ) VALUES (?,?,?,?,?,?,?)' and 7 args, changed 1 rows
777[20:49:04] [Server thread/TRACE] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: insert arguments: [Jamefrus, null, *, null, null, 1459104544438, -1]
778[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: assigned id '7' from keyholder to 'id' in UserPermission object
779[20:49:04] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: connection is closed returned false
780[20:49:04] [Server thread/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/]: compiled statement: SELECT * FROM `permissions_users` 
781[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
782[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: starting iterator @1464054519 for 'SELECT * FROM `permissions_users` '
783[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.SelectIterator/]: closed iterator @1464054519 after 6 rows
784[20:49:04] [Server thread/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/]: query of 'SELECT * FROM `permissions_users` ' returned 6 results
785[20:49:16] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
786[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: java.lang.reflect.InvocationTargetException
787[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
788[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
789[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
790[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.reflect.Method.invoke(Method.java:498)
791[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.invokeCommand(CommandSet.java:73)
792[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.resolveAndInvoke(CommandSet.java:60)
793[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.func_184881_a(CommandSet.java:147)
794[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_175786_a(CommandHandler.java:102)
795[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_71556_a(CommandHandler.java:81)
796[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147361_d(NetHandlerPlayServer.java:916)
797[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147354_a(NetHandlerPlayServer.java:895)
798[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:37)
799[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:9)
800[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.PacketThreadUtil$1.run(SourceFile:13)
801[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
802[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
803[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.util.Util.func_181617_a(SourceFile:45)
804[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71190_q(MinecraftServer.java:664)
805[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.dedicated.DedicatedServer.func_71190_q(DedicatedServer.java:386)
806[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71217_p(MinecraftServer.java:609)
807[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.run(MinecraftServer.java:467)
808[20:49:33] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.Thread.run(Thread.java:745)
809[20:49:33] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: Caused by: java.lang.NullPointerException
810[20:49:59] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
811[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
812[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
813[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
814[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1457784754 for 'SELECT * FROM `groups` '
815[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1457784754 after 0 rows
816[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
817[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
818[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
819[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
820[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @1984221547 for 'SELECT * FROM `permissions_groups` '
821[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @1984221547 after 0 rows
822[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
823[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
824[20:50:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
825[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
826[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @54137447 for 'SELECT * FROM `permissions_users` '
827[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @54137447 after 6 rows
828[20:50:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 6 results
829[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: java.lang.reflect.InvocationTargetException
830[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
831[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
832[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
833[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.reflect.Method.invoke(Method.java:498)
834[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.invokeCommand(CommandSet.java:73)
835[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.resolveAndInvoke(CommandSet.java:60)
836[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at ru.redenergy.rebin.CommandSet.func_184881_a(CommandSet.java:147)
837[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_175786_a(CommandHandler.java:102)
838[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.command.CommandHandler.func_71556_a(CommandHandler.java:81)
839[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147361_d(NetHandlerPlayServer.java:916)
840[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.NetHandlerPlayServer.func_147354_a(NetHandlerPlayServer.java:895)
841[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:37)
842[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.play.client.CPacketChatMessage.func_148833_a(SourceFile:9)
843[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.network.PacketThreadUtil$1.run(SourceFile:13)
844[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
845[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
846[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.util.Util.func_181617_a(SourceFile:45)
847[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71190_q(MinecraftServer.java:664)
848[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.dedicated.DedicatedServer.func_71190_q(DedicatedServer.java:386)
849[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.func_71217_p(MinecraftServer.java:609)
850[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at net.minecraft.server.MinecraftServer.run(MinecraftServer.java:467)
851[20:50:32] [Server thread/INFO] [STDERR/]: [ru.redenergy.rebin.CommandSet:func_184881_a:150]: 	at java.lang.Thread.run(Thread.java:745)
852[20:50:32] [Server thread/INFO] [STDERR/]: [java.lang.Throwable:printStackTrace:634]: Caused by: java.lang.NullPointerException
853[20:50:45] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world
854[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
855[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `groups` 
856[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `groups` ' with 0 args
857[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @899670814 for 'SELECT * FROM `groups` '
858[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @899670814 after 0 rows
859[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `groups` ' returned 0 results
860[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
861[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_groups` 
862[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_groups` ' with 0 args
863[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @960015242 for 'SELECT * FROM `permissions_groups` '
864[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @960015242 after 0 rows
865[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_groups` ' returned 0 results
866[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: connection is closed returned false
867[20:51:02] [pool-2-thread-2/TRACE] [com.j256.ormlite.jdbc.JdbcDatabaseConnection/permissions]: compiled statement: SELECT * FROM `permissions_users` 
868[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.mapped.BaseMappedStatement/permissions]: prepared statement 'SELECT * FROM `permissions_users` ' with 0 args
869[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: starting iterator @333426823 for 'SELECT * FROM `permissions_users` '
870[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.SelectIterator/permissions]: closed iterator @333426823 after 6 rows
871[20:51:02] [pool-2-thread-2/DEBUG] [com.j256.ormlite.stmt.StatementExecutor/permissions]: query of 'SELECT * FROM `permissions_users` ' returned 6 results
872[20:51:09] [Netty Epoll Server IO #5/TRACE] [FML/]: Handshake channel activating
873[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: null->FMLHandshakeServerState$1:START
874[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: HELLO
875[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $ClientHello->FMLHandshakeServerState$2:HELLO
876[20:51:09] [Netty Epoll Server IO #5/INFO] [FML/]: Client protocol version 2
877[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: HELLO
878[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $ModList:6 mods->FMLHandshakeServerState$2:HELLO
879[20:51:09] [Netty Epoll Server IO #5/INFO] [FML/]: Client attempting to join with 6 mods : FML@8.0.99.99,permissions@1.0,Forge@12.16.0.1770,server-i18n@1.0,mcp@9.19,commands@1.0
880[20:51:09] [Netty Epoll Server IO #5/INFO] [FML/]: Attempting connection with missing mods [permissions-commands, SkyEcon] at CLIENT
881[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: WAITINGCACK
882[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{2}->FMLHandshakeServerState$3:WAITINGCACK
883[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: COMPLETE
884[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{3}->FMLHandshakeServerState$4:COMPLETE
885[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: DONE
886[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{4}->FMLHandshakeServerState$5:DONE
887[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: DONE
888[20:51:09] [Server thread/INFO] [FML/]: [Server thread] Server side modded connection established
889[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]: FMLHandshakeServerState: $HandshakeAck:{5}->FMLHandshakeServerState$5:DONE
890[20:51:09] [Netty Epoll Server IO #5/DEBUG] [FML/]:   Next: DONE
891[20:51:22] [Netty Epoll Server IO #4/ERROR] [FML/]: NetworkDispatcher exception
892java.io.IOException: Error while read(...): Connection reset by peer
893	at io.netty.channel.epoll.Native.readAddress(Native Method) ~[Native.class:4.0.23.Final]
894	at io.netty.channel.epoll.EpollSocketChannel$EpollSocketUnsafe.doReadBytes(EpollSocketChannel.java:675) [EpollSocketChannel$EpollSocketUnsafe.class:4.0.23.Final]
895	at io.netty.channel.epoll.EpollSocketChannel$EpollSocketUnsafe.epollInReady(EpollSocketChannel.java:714) [EpollSocketChannel$EpollSocketUnsafe.class:4.0.23.Final]
896	at io.netty.channel.epoll.EpollEventLoop.processReady(EpollEventLoop.java:326) [EpollEventLoop.class:4.0.23.Final]
897	at io.netty.channel.epoll.EpollEventLoop.run(EpollEventLoop.java:264) [EpollEventLoop.class:4.0.23.Final]
898	at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:116) [SingleThreadEventExecutor$2.class:4.0.23.Final]
899	at java.lang.Thread.run(Thread.java:745) [?:1.8.0_72]
900[20:51:30] [Server thread/DEBUG] [FML/]: Gathering id map for writing to world save world