CreeperHost / FTB-Backups-2

7 stars 9 forks source link

A strange exception was thrown during the backup #43

Closed Orisland closed 1 year ago

Orisland commented 1 year ago

My English is poor, so I used Google Translate to illustrate the problem. The Modpacks used is Create:Astral : url When the server tries to back up, the following exception will be thrown repeatedly. This exception does not always occur, and the time it occurs is not fixed and will not cause the server to crash. However, this problem will cause the backup task to fail to execute normally for a long time and cause the backup file to be missing. Looks like the first failed backup job didn't finish properly.

[01:00:00] [ftbbackups2_Worker-1/INFO]: Attempting to create an automatic backup
[01:00:00] [ftbbackups2_Worker-1/INFO]: Found world folder at E:\CA\.\world\.
[01:00:00] [ftbbackups2_Worker-1/INFO]: Unable to start new backup as backup is already running
[01:00:00] [ftbbackups2_Worker-1/ERROR]: Unable to create backup, Reason: Unable to start new backup as backup is already running
[01:30:00] [ftbbackups2_Worker-1/INFO]: Attempting to create an automatic backup
[01:30:00] [ftbbackups2_Worker-1/INFO]: Found world folder at E:\CA\.\world\.
[01:30:00] [ftbbackups2_Worker-1/INFO]: Last backup size: 1.6GB Current world size: 2.4GB Current free space: 171.5GB ExpectedSize 1.7GB
[01:30:00] [ftbbackups2_Worker-1/ERROR]: Job DEFAULT.ftbbackups2 threw an unhandled Exception: 
java.lang.ArrayIndexOutOfBoundsException: Index -1 out of bounds for length 33
        at it.unimi.dsi.fastutil.longs.LongLinkedOpenHashSet.removeFirstLong(LongLinkedOpenHashSet.java:601) ~[fastutil-8.5.6.jar:?]
        at net.minecraft.class_3554.method_15492(class_3554.java:207) ~[server-intermediary.jar:?]
        at net.minecraft.class_3204$class_4077.method_18746(class_3204.java:513) ~[server-intermediary.jar:?]
        at net.minecraft.class_3204.method_15892(class_3204.java:126) ~[server-intermediary.jar:?]
        at net.minecraft.class_3215.method_16155(class_3215.java:282) ~[server-intermediary.jar:?]
        at net.minecraft.class_3215.method_17298(class_3215.java:304) ~[server-intermediary.jar:?]
        at net.minecraft.class_3218.method_14176(class_3218.java:752) ~[server-intermediary.jar:?]
        at net.minecraft.server.MinecraftServer.method_3723(MinecraftServer.java:564) ~[server-intermediary.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.createBackup(BackupHandler.java:197) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.createBackup(BackupHandler.java:174) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.FTBBackups$BackupJob.execute(FTBBackups.java:133) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.org.quartz.core.JobRunShell.run(JobRunShell.java:206) [ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:548) [ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
[01:30:00] [ftbbackups2_Worker-1/ERROR]: Job (DEFAULT.ftbbackups2 threw an exception.
net.creeperhost.ftbbackups.org.quartz.SchedulerException: Job threw an unhandled exception.
        at net.creeperhost.ftbbackups.org.quartz.core.JobRunShell.run(JobRunShell.java:217) [ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:548) [ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
Caused by: java.lang.ArrayIndexOutOfBoundsException: Index -1 out of bounds for length 33
        at it.unimi.dsi.fastutil.longs.LongLinkedOpenHashSet.removeFirstLong(LongLinkedOpenHashSet.java:601) ~[fastutil-8.5.6.jar:?]
        at net.minecraft.class_3554.method_15492(class_3554.java:207) ~[server-intermediary.jar:?]
        at net.minecraft.class_3204$class_4077.method_18746(class_3204.java:513) ~[server-intermediary.jar:?]
        at net.minecraft.class_3204.method_15892(class_3204.java:126) ~[server-intermediary.jar:?]
        at net.minecraft.class_3215.method_16155(class_3215.java:282) ~[server-intermediary.jar:?]
        at net.minecraft.class_3215.method_17298(class_3215.java:304) ~[server-intermediary.jar:?]
        at net.minecraft.class_3218.method_14176(class_3218.java:752) ~[server-intermediary.jar:?]
        at net.minecraft.server.MinecraftServer.method_3723(MinecraftServer.java:564) ~[server-intermediary.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.createBackup(BackupHandler.java:197) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.createBackup(BackupHandler.java:174) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.FTBBackups$BackupJob.execute(FTBBackups.java:133) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        at net.creeperhost.ftbbackups.org.quartz.core.JobRunShell.run(JobRunShell.java:206) ~[ftbbackups2-fabric-1.18.2-1.0.16.jar:?]
        ... 1 more
[02:00:00] [ftbbackups2_Worker-1/INFO]: Attempting to create an automatic backup
[02:00:00] [ftbbackups2_Worker-1/INFO]: Found world folder at E:\CA\.\world\.
[02:00:00] [ftbbackups2_Worker-1/INFO]: Unable to start new backup as backup is already running
[02:00:00] [ftbbackups2_Worker-1/ERROR]: Unable to create backup, Reason: Unable to start new backup as backup is already running
[02:30:00] [ftbbackups2_Worker-1/INFO]: Attempting to create an automatic backup
[02:30:00] [ftbbackups2_Worker-1/INFO]: Found world folder at E:\CA\.\world\.
[02:30:00] [ftbbackups2_Worker-1/INFO]: Last backup size: 1.6GB Current world size: 2.4GB Current free space: 171.5GB ExpectedSize 1.7GB
[02:30:00] [ftbbackups2_Worker-1/ERROR]: Job DEFAULT.ftbbackups2 threw an unhandled Exception: 
java.lang.ArrayIndexOutOfBoundsException: Index -1 out of bounds for length 33
        at it.unimi.dsi.fastutil.longs.LongLinkedOpenHashSet.removeFirstLong(LongLinkedOpenHashSet.java:601) ~[fastutil-8.5.6.jar:?]
        at net.minecraft.class_3554.method_15492(class_3554.java:207) ~[server-intermediary.jar:?]
...
Orisland commented 1 year ago

Subsequent verification of the exception will cause the server to enter a state of suspended animation and cannot end when it is restarted routinely or crashes due to other problems. In this case, you need to manually forcibly end the server process.

Orisland commented 1 year ago

It seems that the cause of the server throwing an exception crash seems to be this mod as well. crash report

SkyIce0229 commented 1 year ago

The same problem occurs in FTB StoneBlock3.

Orisland commented 1 year ago

The same problem occurs in FTB StoneBlock3.

I tried building the latest version of the mod myself and the problem seemed to be fixed, but new other problems popped up. Fortunately, unlike before, this time it's just a warning and not an error.

1/INFO]: Attempting to create an automatic backup
[22:30:00] [ftbbackups2_Worker-1/INFO]: Found world folder at E:\CA\.\world\.
[22:30:00] [ftbbackups2_Worker-1/INFO]: Last backup size: 2.1GB Current world size: 3.0GB Current free space: 159.2GB ExpectedSize 2.2GB
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world minecraft:overworld save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:earth_orbit save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:glacio_orbit save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:glacio save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world minecraft:the_nether save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:mercury_orbit save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:venus_orbit save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:moon_orbit save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:moon save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:venus save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world nullscape:end_island save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:mercury save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world minecraft:the_end save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:mars save state to true
[22:30:00] [ftbbackups2_Worker-1/INFO]: Setting world ad_astra:mars_orbit save state to true
[22:30:00] [FTB Backups backup thread 0/INFO]: Rendering region file 0 -1
[22:30:00] [Server thread/INFO]: Saving grave backup data
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (world): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (earth_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (glacio_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (glacio): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (DIM-1): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (mercury_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (venus_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (moon_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (moon): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (venus): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (end_island): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (mercury): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (DIM1): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (mars): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage (mars_orbit): All chunks are saved
[22:30:00] [Server thread/INFO]: ThreadedAnvilChunkStorage: All dimensions are saved
[22:30:01] [FTB Backups backup thread 0/WARN]: Failed to load chunk ( 9.000E+0  2.700E+1)
java.lang.IllegalArgumentException: Unsupported compression type, must be between 0 and 2 (inclusive)
        at net.creeperhost.ftbbackups.de.piegames.nbt.stream.NBTInputStream.<init>(NBTInputStream.java:159) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.de.piegames.nbt.stream.NBTInputStream.<init>(NBTInputStream.java:111) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.de.piegames.blockmap.renderer.RegionRenderer.renderRaw(RegionRenderer.java:117) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.de.piegames.blockmap.renderer.RegionRenderer.render(RegionRenderer.java:61) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.de.piegames.blockmap.world.RegionFolder$WorldRegionFolder.render(RegionFolder.java:179) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.de.piegames.blockmap.world.RegionFolder$CachedRegionFolder.render(RegionFolder.java:467) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.createPreview(BackupHandler.java:150) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at net.creeperhost.ftbbackups.BackupHandler.lambda$createBackup$4(BackupHandler.java:260) ~[ftbbackups2-fabric-1.18.2-1.0.17.jar:?]
        at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1804) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:833) [?:?]
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world minecraft:overworld save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:earth_orbit save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:glacio_orbit save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:glacio save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world minecraft:the_nether save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:mercury_orbit save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:venus_orbit save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:moon_orbit save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:moon save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:venus save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world nullscape:end_island save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:mercury save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world minecraft:the_end save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:mars save state to false
[22:31:24] [FTB Backups backup thread 0/INFO]: Setting world ad_astra:mars_orbit save state to false
[22:31:30] [FTB Backups backup thread 0/INFO]: Backup size 2.1GB World Size 3.0GB
[22:31:30] [FTB Backups backup thread 0/INFO]: Writing to file E:\CA\backups\backups.json
[22:31:30] [FTB Backups backup thread 0/INFO]: New backup created at E:\CA\backups\2022-12-6_22-30-0.zip size: 2.1GB Took: 1m, 24s, 60010ms Sha1: a527ea2c74fb1f40b6edecad78af2062e8874b67
[22:31:37] [FTB Backups scheduled executor 0/INFO]: More backups than 5 found, Removing oldest backup
[22:31:37] [FTB Backups scheduled executor 0/INFO]: Removed old backup 2022-12-6_20-0-0.zip
[22:31:37] [FTB Backups scheduled executor 0/INFO]: File missing, removing from backups E:\CA\backups\2022-12-6_20-0-0.zip
[22:31:37] [FTB Backups scheduled executor 0/INFO]: Writing to file E:\CA\backups\backups.json
macks2008 commented 1 year ago

This issues appears to be a duplicate of #38, which is fixed in dev and does not occur in the latest releases.

@Orisland Your translation was almost perfect. Would you like extended feedback on it? I like to help potential English-language learners. But I don't want to impose

Orisland commented 1 year ago

此问题似乎与#38重复,后者已在开发中修复并且不会出现在最新版本中。

@Orisland你的翻译近乎完美。你想要更多的反馈吗?我喜欢帮助潜在的英语学习者。但我不想强加

Sorry, I don't check my email very often. Are you an employee of a relevant training institution?

gigabit101 commented 1 year ago

This is only an error and should not cause any issues, We will attempt so silence this error in the next version

gigabit101 commented 1 year ago

dupe of #39