search

Zerthick / PlayerShopsRPG

A Minecraft Sponge PlayerShop Plugin
https://ore.spongepowered.org/Zerthick/PlayerShopsRPG
GNU General Public License v2.0
7 stars 5 forks source link

HikariPool-5 / Timed Out / Spam Error #51

Open Rosychu opened 4 years ago

Rosychu commented 4 years ago

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!