Date: Tue, 15 Jan 2019 04:08:26 +0000 (UTC) From: Jurgen Segaert <jsegaert@yahoo.com> To: "java@FreeBSD.org" <java@FreeBSD.org> Subject: Java lockups as of 8.192.26 (revision 486973) Message-ID: <223737317.183662.1547525306104@mail.yahoo.com> References: <223737317.183662.1547525306104.ref@mail.yahoo.com>
next in thread | previous in thread | raw e-mail | index | archive | help
[-- Attachment #1 --] Dear Java Maintainer(s), I want to report some unexpected java lockups when running Minecraft server, i.e. the vanilla minecraft_server.1.13.2.jar from https://minecraft.net/en-us/download/server/This is a closed source jar file as published by Mojang. This has been working for many moons w/o issues, but now the Minecraft server consistently locks up as soon as it is started up. The built-in watchdog kills it after 60 seconds. Older versions of the server.jar also exhibit the same issue. I'm running 11.2-STABLE and openjdk8-jre from the latest packages. In an attempt to try to narrow down the issue, I've been rebuilding openjdk8-jre from ports using Subversion and it turns out the last version that is still working is revision 485094, i.e. Java version 8.181.13_1. Revision 486973 is "broken", i.e. Java version 8.192.26 Crash report with more information is attached. At this point I'm stuck. :-( I'm not sure if I'm the first one to report this issue, or if this is the correct channel. If not, please point me in right direction.If you have any questions or need me to try/test anything, please let me know.Thank you, Jurgen Segaert [-- Attachment #2 --] ---- Minecraft Crash Report ---- // Why is it breaking :( Time: 1/14/19 7:46 PM Description: Watching Server java.lang.Error at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.CompletableFuture$Signaller.block(CompletableFuture.java:1693) at java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3323) at java.util.concurrent.CompletableFuture.waitingGet(CompletableFuture.java:1729) at java.util.concurrent.CompletableFuture.join(CompletableFuture.java:1934) at tc.a(SourceFile:122) at axy.c(SourceFile:248) at ayl.<init>(SourceFile:44) at aiw.b(SourceFile:127) at aiv.b(SourceFile:51) at aiv.a(SourceFile:73) at ahe.a(SourceFile:39) at agu.a(SourceFile:94) at afb.cM(SourceFile:630) at afa.k(SourceFile:1968) at afb.k(SourceFile:495) at aeo.k(SourceFile:134) at ajq.k(SourceFile:46) at akm.k(SourceFile:205) at afa.R_(SourceFile:1819) at afb.R_(SourceFile:279) at akm.R_(SourceFile:220) at axy.a(SourceFile:1243) at td.a(SourceFile:575) at axy.g(SourceFile:1218) at axy.o_(SourceFile:1100) at td.o_(SourceFile:491) at net.minecraft.server.MinecraftServer.b(SourceFile:755) at so.b(SourceFile:360) at net.minecraft.server.MinecraftServer.a(SourceFile:681) at net.minecraft.server.MinecraftServer.run(SourceFile:584) at java.lang.Thread.run(Thread.java:748) A detailed walkthrough of the error, its code path and all known details is as follows: --------------------------------------------------------------------------------------- -- Head -- Thread: Server Watchdog Stacktrace: at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.CompletableFuture$Signaller.block(CompletableFuture.java:1693) at java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3323) at java.util.concurrent.CompletableFuture.waitingGet(CompletableFuture.java:1729) at java.util.concurrent.CompletableFuture.join(CompletableFuture.java:1934) at tc.a(SourceFile:122) at axy.c(SourceFile:248) at ayl.<init>(SourceFile:44) at aiw.b(SourceFile:127) at aiv.b(SourceFile:51) at aiv.a(SourceFile:73) at ahe.a(SourceFile:39) at agu.a(SourceFile:94) at afb.cM(SourceFile:630) at afa.k(SourceFile:1968) at afb.k(SourceFile:495) at aeo.k(SourceFile:134) at ajq.k(SourceFile:46) at akm.k(SourceFile:205) at afa.R_(SourceFile:1819) at afb.R_(SourceFile:279) at akm.R_(SourceFile:220) at axy.a(SourceFile:1243) at td.a(SourceFile:575) at axy.g(SourceFile:1218) at axy.o_(SourceFile:1100) at td.o_(SourceFile:491) at net.minecraft.server.MinecraftServer.b(SourceFile:755) at so.b(SourceFile:360) at net.minecraft.server.MinecraftServer.a(SourceFile:681) -- Thread Dump -- Details: Threads: "Netty Server IO #7" Id=33 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@309e16a - locked java.util.Collections$UnmodifiableSet@dce1f7b - locked sun.nio.ch.KQueueSelectorImpl@9f18a1a at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "File IO Thread" Id=32 TIMED_WAITING at java.lang.Thread.sleep(Native Method) at cdw.c(SourceFile:59) at cdw.run(SourceFile:35) at java.lang.Thread.run(Thread.java:748) "Server Watchdog" Id=31 RUNNABLE at sun.management.ThreadImpl.dumpThreads0(Native Method) at sun.management.ThreadImpl.dumpAllThreads(ThreadImpl.java:454) at sp.run(SourceFile:45) at java.lang.Thread.run(Thread.java:748) "Netty Server IO #6" Id=30 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@4cf31540 - locked java.util.Collections$UnmodifiableSet@bdbf05 - locked sun.nio.ch.KQueueSelectorImpl@7d755dae at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "Netty Server IO #5" Id=29 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@78d1a940 - locked java.util.Collections$UnmodifiableSet@2cdcea0f - locked sun.nio.ch.KQueueSelectorImpl@41f43d06 at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "Netty Server IO #4" Id=28 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@16bb80bb - locked java.util.Collections$UnmodifiableSet@63d5a085 - locked sun.nio.ch.KQueueSelectorImpl@2892b262 at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "Netty Server IO #3" Id=27 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@486eb67b - locked java.util.Collections$UnmodifiableSet@29a5995 - locked sun.nio.ch.KQueueSelectorImpl@4c8500cb at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "Netty Server IO #2" Id=26 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@71b9ba9b - locked java.util.Collections$UnmodifiableSet@4c5917b9 - locked sun.nio.ch.KQueueSelectorImpl@1cdfa956 at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "WorldGen-Worker-1" Id=25 RUNNABLE at byj.a(SourceFile:61) at bov.a(SourceFile:195) at bov.a(SourceFile:129) at bov.a(SourceFile:99) at to.a(SourceFile:13) at tq.a(SourceFile:35) at bnd.a(SourceFile:95) at tx.a(SourceFile:79) ... "WorldGen-Scheduler-1" Id=24 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@78b8715e at sun.misc.Unsafe.park(Native Method) - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@78b8715e at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) "Netty Server IO #1" Id=23 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@44e961b0 - locked java.util.Collections$UnmodifiableSet@4009424b - locked sun.nio.ch.KQueueSelectorImpl@217dd605 at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "Netty Server IO #0" Id=22 RUNNABLE (in native) at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:198) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:117) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) - locked io.netty.channel.nio.SelectedSelectionKeySet@1a7461f5 - locked java.util.Collections$UnmodifiableSet@151f67ea - locked sun.nio.ch.KQueueSelectorImpl@1f8e7418 at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at io.netty.channel.nio.SelectedSelectionKeySetSelector.select(SelectedSelectionKeySetSelector.java:62) at io.netty.channel.nio.NioEventLoop.select(NioEventLoop.java:756) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:411) ... "ObjectCleanerThread" Id=21 TIMED_WAITING on java.lang.ref.ReferenceQueue$Lock@508c4412 at java.lang.Object.wait(Native Method) - waiting on java.lang.ref.ReferenceQueue$Lock@508c4412 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144) at io.netty.util.internal.ObjectCleaner$1.run(ObjectCleaner.java:54) at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) at java.lang.Thread.run(Thread.java:748) "Server console handler" Id=20 RUNNABLE (in native) at java.io.FileInputStream.readBytes(Native Method) at java.io.FileInputStream.read(FileInputStream.java:255) at java.io.BufferedInputStream.read1(BufferedInputStream.java:284) at java.io.BufferedInputStream.read(BufferedInputStream.java:345) - locked java.io.BufferedInputStream@694eec3f at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284) at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326) at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178) - locked java.io.InputStreamReader@700f6cb at java.io.InputStreamReader.read(InputStreamReader.java:184) ... "DestroyJavaVM" Id=19 RUNNABLE "Server thread" Id=17 WAITING on java.util.concurrent.CompletableFuture$Signaller@6b426c87 at sun.misc.Unsafe.park(Native Method) - waiting on java.util.concurrent.CompletableFuture$Signaller@6b426c87 at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.CompletableFuture$Signaller.block(CompletableFuture.java:1693) at java.util.concurrent.ForkJoinPool.managedBlock(ForkJoinPool.java:3323) at java.util.concurrent.CompletableFuture.waitingGet(CompletableFuture.java:1729) at java.util.concurrent.CompletableFuture.join(CompletableFuture.java:1934) at tc.a(SourceFile:122) at axy.c(SourceFile:248) ... "Server Infinisleeper" Id=16 TIMED_WAITING at java.lang.Thread.sleep(Native Method) at so$1.run(SourceFile:94) "Snooper Timer" Id=15 WAITING on java.util.TaskQueue@595c1929 at java.lang.Object.wait(Native Method) - waiting on java.util.TaskQueue@595c1929 at java.lang.Object.wait(Object.java:502) at java.util.TimerThread.mainLoop(Timer.java:526) at java.util.TimerThread.run(Timer.java:505) "Signal Dispatcher" Id=4 RUNNABLE "Finalizer" Id=3 WAITING on java.lang.ref.ReferenceQueue$Lock@476d3929 at java.lang.Object.wait(Native Method) - waiting on java.lang.ref.ReferenceQueue$Lock@476d3929 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:216) "Reference Handler" Id=2 WAITING on java.lang.ref.Reference$Lock@47db39a1 at java.lang.Object.wait(Native Method) - waiting on java.lang.ref.Reference$Lock@47db39a1 at java.lang.Object.wait(Object.java:502) at java.lang.ref.Reference.tryHandlePending(Reference.java:191) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153) Stacktrace: at sp.run(SourceFile:61) at java.lang.Thread.run(Thread.java:748) -- System Details -- Details: Minecraft Version: 1.13.2 Operating System: FreeBSD (amd64) version 11.2-STABLE Java Version: 1.8.0_192, Oracle Corporation Java VM Version: OpenJDK 64-Bit Server VM (mixed mode), Oracle Corporation Memory: 486355800 bytes (463 MB) / 956301312 bytes (912 MB) up to 956301312 bytes (912 MB) JVM Flags: 2 total; -Xmx1024M -Xms1024M Profiler Position: N/A (disabled) Player Count: 0 / 20; [] Data Packs: vanilla Is Modded: Unknown (can't tell) Type: Dedicated Server (map_server.txt) [-- Attachment #3 --] [19:45:22] [Server thread/INFO]: Starting minecraft server version 1.13.2 [19:45:22] [Server thread/INFO]: Loading properties [19:45:22] [Server thread/INFO]: Default game type: CREATIVE [19:45:22] [Server thread/INFO]: Generating keypair [19:45:23] [Server thread/INFO]: Starting Minecraft server on 0.0.0.0:25565 [19:45:23] [Server thread/INFO]: Using default channel type [19:45:23] [Server thread/INFO]: Preparing level "world" [19:45:23] [Server thread/INFO]: Found new data pack vanilla, loading it automatically [19:45:23] [Server thread/INFO]: Reloading ResourceManager: Default [19:45:25] [Server thread/INFO]: Loaded 524 recipes [19:45:26] [Server thread/INFO]: Loaded 571 advancements [19:45:35] [Server thread/INFO]: Preparing start region for dimension minecraft:overworld [19:45:36] [Server thread/INFO]: Preparing spawn area: 0% [19:45:37] [Server thread/INFO]: Preparing spawn area: 0% [19:45:38] [Server thread/INFO]: Preparing spawn area: 0% [19:45:39] [Server thread/INFO]: Preparing spawn area: 0% [19:45:40] [Server thread/INFO]: Preparing spawn area: 4% [19:45:41] [Server thread/INFO]: Preparing spawn area: 8% [19:45:41] [Server thread/INFO]: Preparing spawn area: 12% [19:45:42] [Server thread/INFO]: Preparing spawn area: 16% [19:45:42] [Server thread/INFO]: Preparing spawn area: 20% [19:45:43] [Server thread/INFO]: Preparing spawn area: 24% [19:45:43] [Server thread/INFO]: Preparing spawn area: 28% [19:45:44] [Server thread/INFO]: Preparing spawn area: 32% [19:45:44] [Server thread/INFO]: Preparing spawn area: 36% [19:45:45] [Server thread/INFO]: Preparing spawn area: 40% [19:45:45] [Server thread/INFO]: Preparing spawn area: 44% [19:45:46] [Server thread/INFO]: Preparing spawn area: 48% [19:45:46] [Server thread/INFO]: Preparing spawn area: 52% [19:45:47] [Server thread/INFO]: Preparing spawn area: 56% [19:45:47] [Server thread/INFO]: Preparing spawn area: 60% [19:45:48] [Server thread/INFO]: Preparing spawn area: 64% [19:45:49] [Server thread/INFO]: Preparing spawn area: 68% [19:45:49] [Server thread/INFO]: Preparing spawn area: 72% [19:45:50] [Server thread/INFO]: Preparing spawn area: 76% [19:45:50] [Server thread/INFO]: Preparing spawn area: 80% [19:45:51] [Server thread/INFO]: Preparing spawn area: 84% [19:45:51] [Server thread/INFO]: Preparing spawn area: 88% [19:45:52] [Server thread/INFO]: Preparing spawn area: 92% [19:45:53] [Server thread/INFO]: Preparing spawn area: 96% [19:45:53] [Server thread/INFO]: Preparing spawn area: 100% [19:45:53] [Server thread/INFO]: Time elapsed: 18287 ms [19:45:53] [Server thread/INFO]: Done (29.786s)! For help, type "help" [19:45:54] [Server thread/INFO]: Unknown command [19:45:54] [Server thread/INFO]: ls<--[HERE] [19:45:54] [Server thread/INFO]: There are 0 of a max 20 players online: [19:46:48] [User Authenticator #1/INFO]: UUID of player Moonrise_gazer is ae8df01a-7b30-4c2b-b682-55871ce64f2c [19:46:54] [Server Watchdog/FATAL]: A single server tick took 60.01 seconds (should be max 0.05) [19:46:54] [Server Watchdog/FATAL]: Considering it to be crashed, server will forcibly shutdown. [19:46:54] [Server Watchdog/ERROR]: This crash report has been saved to: /var/games/minecraft/servers/test/./crash-reports/crash-2019-01-14_19.46.54-server.txt [19:46:54] [Server Shutdown Thread/INFO]: Stopping server [19:46:54] [Server Shutdown Thread/INFO]: Saving players [19:46:54] [Server Shutdown Thread/INFO]: Saving worlds [19:46:54] [Server Shutdown Thread/INFO]: Saving chunks for level 'world'/minecraft:the_end
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?223737317.183662.1547525306104>