Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

HikariPool-5 / Timed Out / Spam Error #51

Open
Rosychu opened this issue Jun 15, 2020 · 0 comments
Open

HikariPool-5 / Timed Out / Spam Error #51

Rosychu opened this issue Jun 15, 2020 · 0 comments

Comments

@Rosychu
Copy link

Rosychu commented Jun 15, 2020

SPONGEAPI: 7.10-ff990df
SPONGEFORGE: 1.1.2-2705-7.10-BETA-3481
Plugin Version: 2.1.1

Hello again! I've noticed something going on in our logs that is spamming them up, and preventing the server from closing down properly? Or... something weird! Shops are also not being saved properly because of that, I'm guessing.

At start up it does:

[12:23:30] [Server thread/INFO] [com.zaxxer.hikari.HikariDataSource]: HikariPool-5 - Start completed.
[12:23:31] [pool-3-thread-4/INFO] [playershopsrpg]: Saving Shops to DB
[12:23:31] [Server thread/INFO] [coloranvil]: ColorAnvil version 1.2.0 enabled!
[12:23:31] [pool-3-thread-4/ERROR] [playershopsrpg]: The database is read only; SQL statement:
MERGE INTO SHOP VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [90097-196]
[12:23:31] [pool-3-thread-4/ERROR] [playershopsrpg]: The database is read only; SQL statement:
MERGE INTO SHOP_REGION VALUES (?, ?, ?, ?, ?) [90097-196]
[12:23:31] [pool-3-thread-4/ERROR] [playershopsrpg]: The database is read only; SQL statement:
MERGE INTO SHOP VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [90097-196]

and repeats

And this when I try to close the server:

java.lang.Exception: Apparent connection leak detected
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLUtil.executeUpdate(SQLUtil.java:125) ~[SQLUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShop(SQLDataUtil.java:222) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShopContainter(SQLDataUtil.java:280) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShopContainers(SQLDataUtil.java:304) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.ConfigManager.lambda$saveShops$1(ConfigManager.java:111) ~[ConfigManager.class:?]
	at java.util.HashMap.forEach(HashMap.java:1289) ~[?:1.8.0_222]
	at io.github.zerthick.playershopsrpg.utils.config.ConfigManager.saveShops(ConfigManager.java:111) ~[ConfigManager.class:?]
	at io.github.zerthick.playershopsrpg.PlayerShopsRPG.onServerStop(PlayerShopsRPG.java:217) ~[PlayerShopsRPG.class:?]
	at org.spongepowered.common.event.listener.GameStoppedServerEventListener_PlayerShopsRPG_onServerStop73.handle(Unknown Source) ~[?:?]
	at org.spongepowered.common.event.RegisteredListener.handle(RegisteredListener.java:95) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.post(SpongeModEventManager.java:383) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.extendedPost(SpongeModEventManager.java:443) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.post(SpongeModEventManager.java:404) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.common.SpongeImpl.postEvent(SpongeImpl.java:231) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.SpongeMod.onStateEvent(SpongeMod.java:271) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at com.google.common.eventbus.Subscriber.invokeSubscriberMethod(Subscriber.java:91) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$SynchronizedSubscriber.invokeSubscriberMethod(Subscriber.java:150) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$1.run(Subscriber.java:76) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:399) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber.dispatchEvent(Subscriber.java:71) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Dispatcher$PerThreadQueuedDispatcher.dispatch(Dispatcher.java:116) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.EventBus.post(EventBus.java:217) ~[minecraft_server.1.12.2.jar:?]
	at net.minecraftforge.fml.common.LoadController.sendEventToModContainer(LoadController.java:219) ~[minecraft_server.jar:?]
	at net.minecraftforge.fml.common.LoadController.propogateStateMessage(LoadController.java:197) ~[minecraft_server.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at com.google.common.eventbus.Subscriber.invokeSubscriberMethod(Subscriber.java:91) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$SynchronizedSubscriber.invokeSubscriberMethod(Subscriber.java:150) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$1.run(Subscriber.java:76) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:399) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber.dispatchEvent(Subscriber.java:71) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Dispatcher$PerThreadQueuedDispatcher.dispatch(Dispatcher.java:116) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.EventBus.post(EventBus.java:217) ~[minecraft_server.1.12.2.jar:?]
	at net.minecraftforge.fml.common.LoadController.redirect$onPost$zza000(LoadController.java:560) ~[LoadController.class:?]
	at net.minecraftforge.fml.common.LoadController.distributeStateMessage(LoadController.java:136) ~[LoadController.class:?]
	at net.minecraftforge.fml.common.Loader.serverStopped(Loader.java:847) ~[Loader.class:?]
	at net.minecraftforge.fml.common.FMLCommonHandler.handleServerStopped(FMLCommonHandler.java:508) ~[FMLCommonHandler.class:?]
	at net.minecraft.server.MinecraftServer.run(MinecraftServer.java:587) ~[MinecraftServer.class:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222] 

In the logs it's just this over and over generally?

[00:00:08] [pool-3-thread-534/ERROR] [playershopsrpg]: HikariPool-6 - Connection is not available, request timed out after 30000ms. 

& On the Debug file is similar:
[06:20:38] [pool-3-thread-599/DEBUG] [com.zaxxer.hikari.pool.HikariPool]: HikariPool-6 - Timeout failure stats (total=17, active=17, idle=0, waiting=10)
repeated
Along with:

java.lang.Exception: Apparent connection leak detected
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLUtil.executeUpdate(SQLUtil.java:125) ~[SQLUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShop(SQLDataUtil.java:222) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShopContainter(SQLDataUtil.java:280) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.sql.SQLDataUtil.saveShopContainers(SQLDataUtil.java:304) ~[SQLDataUtil.class:?]
	at io.github.zerthick.playershopsrpg.utils.config.ConfigManager.lambda$saveShops$1(ConfigManager.java:111) ~[ConfigManager.class:?]
	at java.util.HashMap.forEach(HashMap.java:1289) ~[?:1.8.0_222]
	at io.github.zerthick.playershopsrpg.utils.config.ConfigManager.saveShops(ConfigManager.java:111) ~[ConfigManager.class:?]
	at io.github.zerthick.playershopsrpg.PlayerShopsRPG.onServerStop(PlayerShopsRPG.java:217) ~[PlayerShopsRPG.class:?]
	at org.spongepowered.common.event.listener.GameStoppedServerEventListener_PlayerShopsRPG_onServerStop73.handle(Unknown Source) ~[?:?]
	at org.spongepowered.common.event.RegisteredListener.handle(RegisteredListener.java:95) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.post(SpongeModEventManager.java:383) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.extendedPost(SpongeModEventManager.java:443) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.event.SpongeModEventManager.post(SpongeModEventManager.java:404) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.common.SpongeImpl.postEvent(SpongeImpl.java:231) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at org.spongepowered.mod.SpongeMod.onStateEvent(SpongeMod.java:271) ~[spongeforge-1.12.2-2705-7.1.0-BETA-3481.jar:1.12.2-2705-7.1.0-BETA-3481]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at com.google.common.eventbus.Subscriber.invokeSubscriberMethod(Subscriber.java:91) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$SynchronizedSubscriber.invokeSubscriberMethod(Subscriber.java:150) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$1.run(Subscriber.java:76) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:399) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber.dispatchEvent(Subscriber.java:71) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Dispatcher$PerThreadQueuedDispatcher.dispatch(Dispatcher.java:116) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.EventBus.post(EventBus.java:217) ~[minecraft_server.1.12.2.jar:?]
	at net.minecraftforge.fml.common.LoadController.sendEventToModContainer(LoadController.java:219) ~[minecraft_server.jar:?]
	at net.minecraftforge.fml.common.LoadController.propogateStateMessage(LoadController.java:197) ~[minecraft_server.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_222]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_222]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
	at com.google.common.eventbus.Subscriber.invokeSubscriberMethod(Subscriber.java:91) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$SynchronizedSubscriber.invokeSubscriberMethod(Subscriber.java:150) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber$1.run(Subscriber.java:76) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:399) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Subscriber.dispatchEvent(Subscriber.java:71) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.Dispatcher$PerThreadQueuedDispatcher.dispatch(Dispatcher.java:116) ~[minecraft_server.1.12.2.jar:?]
	at com.google.common.eventbus.EventBus.post(EventBus.java:217) ~[minecraft_server.1.12.2.jar:?]
	at net.minecraftforge.fml.common.LoadController.redirect$onPost$zza000(LoadController.java:560) ~[LoadController.class:?]
	at net.minecraftforge.fml.common.LoadController.distributeStateMessage(LoadController.java:136) ~[LoadController.class:?]
	at net.minecraftforge.fml.common.Loader.serverStopped(Loader.java:847) ~[Loader.class:?]
	at net.minecraftforge.fml.common.FMLCommonHandler.handleServerStopped(FMLCommonHandler.java:508) ~[FMLCommonHandler.class:?]
	at net.minecraft.server.MinecraftServer.run(MinecraftServer.java:587) ~[MinecraftServer.class:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]

I've also tried a few earlier backups I've made of this config folder, but they produce the same sort of errors sadly. I don't think they did that before, but, I can't confirm or remember, they're several months old. I can't trace or pinpoint when it started sadly.

It doesn't produce these errors or hangups at all with a fresh config folder, but I was hoping to find a way to save the shops already done. So any help would be super appreciated! Thanks in advance!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant