[MC-131688] Server - random meaningless: java.io.IOException: Permission denied

No one has connected to this since the last start:
Spurious startup errors like:

[08:48:34] [main/WARN]: Ambiguity between arguments [teleport, destination] and [teleport, targets] with inputs: [Player, 0123, @e, dd12be42-52a9-4a91-a8a1-11c01849e498]
[08:48:34] [main/WARN]: Ambiguity between arguments [teleport, location] and [teleport, destination] with inputs: [0.1 -0.5 .9, 0 0 0]
[08:48:34] [main/WARN]: Ambiguity between arguments [teleport, location] and [teleport, targets] with inputs: [0.1 -0.5 .9, 0 0 0]
[08:48:34] [main/WARN]: Ambiguity between arguments [teleport, targets] and [teleport, destination] with inputs: [Player, 0123, dd12be42-52a9-4a91-a8a1-11c01849e498]
[08:48:34] [main/WARN]: Ambiguity between arguments [teleport, targets, location] and [teleport, targets, destination] with inputs: [0.1 -0.5 .9, 0 0 0]
[08:48:34] [main/INFO]: Loaded 0 recipes
[08:48:34] [main/INFO]: Loaded 0 advancements
[08:48:34] [Server thread/INFO]: Starting minecraft server version 1.13-pre2

Then after a while, these show up, with of course, NO CLUES as to which file it cant access because "java."

[16:51:44] [RCON Listener #1/INFO]: RCON running on 10.0.0.8:25575
[03:02:14] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write0(Native Method)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1030)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at bre.a(SourceFile:324)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at bre.a(SourceFile:263)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at bre$a.close(SourceFile:244)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at bra.b(SourceFile:270)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at bra.a(SourceFile:251)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at cgy.c(SourceFile:42)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at cgy.run(SourceFile:35)
[03:02:14] [File IO Thread/INFO]: [STDERR]:     at java.lang.Thread.run(Thread.java:748)
[05:00:00] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write0(Native Method)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at bre.b(SourceFile:351)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at bre.a(SourceFile:315)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at bre$a.close(SourceFile:244)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at bra.b(SourceFile:270)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at bra.a(SourceFile:251)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at cgy.c(SourceFile:42)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at cgy.run(SourceFile:35)
[05:00:00] [File IO Thread/INFO]: [STDERR]:     at java.lang.Thread.run(Thread.java:748)
[08:07:29] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write0(Native Method)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at bre.b(SourceFile:351)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at bre.a(SourceFile:315)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at bre$a.close(SourceFile:244)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at bra.b(SourceFile:270)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at bra.a(SourceFile:251)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at cgy.c(SourceFile:42)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at cgy.run(SourceFile:35)
[08:07:29] [File IO Thread/INFO]: [STDERR]:     at java.lang.Thread.run(Thread.java:748)

Comments 6

bunny 2018-06-30T03:34:57Z

This is still happening with 1.13-pre4

[08:02:26] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write0(Native Method)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at bru.b(SourceFile:351)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at bru.a(SourceFile:315)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at bru$a.close(SourceFile:244)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at brq.b(SourceFile:269)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at brq.a(SourceFile:250)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at chq.c(SourceFile:42)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at chq.run(SourceFile:35)
[08:02:26] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)
[09:18:11] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write0(Native Method)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at bru.a(SourceFile:324)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at bru.a(SourceFile:263)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at bru$a.close(SourceFile:244)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at brq.b(SourceFile:269)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at brq.a(SourceFile:250)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at chq.c(SourceFile:42)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at chq.run(SourceFile:35)
[09:18:11] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)

bunny 2018-07-02T23:59:39Z

pre5 still generates random permission denied

from logs/latest
[16:42:37] [File IO Thread/INFO]: [STDERR]: at cct.run(SourceFile:35)
[16:42:37] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write0(Native Method)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx.b(SourceFile:351)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx.a(SourceFile:315)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx$a.close(SourceFile:244)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmt.b(SourceFile:270)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmt.a(SourceFile:251)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at cct.c(SourceFile:42)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at cct.run(SourceFile:35)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)
[04:51:35] [File IO Thread/INFO]: [STDERR]: java.io.IOException: Permission denied
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write0(Native Method)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at bmx.b(SourceFile:351)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at bmx.a(SourceFile:315)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at bmx$a.close(SourceFile:244)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at bmt.b(SourceFile:270)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at bmt.a(SourceFile:251)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at cct.c(SourceFile:42)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at cct.run(SourceFile:35)
[04:51:35] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)

----------------------------------------------------------------------------------------------
on screen about the time of the 02.03 error:

at bmx.a(SourceFile:317)
at bmx$a.close(SourceFile:244)
at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
at bmt.b(SourceFile:270)
at bmt.a(SourceFile:251)
at cct.c(SourceFile:42)
at cct.run(SourceFile:35)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.nio.file.AccessDeniedException: logs
at sun.nio.fs.UnixException.translateToIOException(UnixException.java:84)
at sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:102)
at sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:107)
at sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:427)
at java.nio.file.Files.newDirectoryStream(Files.java:457)
at org.apache.logging.log4j.core.appender.rolling.AbstractRolloverStrategy.getEligibleFiles(AbstractRolloverStrategy.java:126)
... 39 more

[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write0(Native Method)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.write(RandomAccessFile.java:489)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.RandomAccessFile.writeInt(RandomAccessFile.java:1031)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx.b(SourceFile:351)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx.a(SourceFile:315)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmx$a.close(SourceFile:244)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.util.zip.DeflaterOutputStream.close(DeflaterOutputStream.java:241)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.io.FilterOutputStream.close(FilterOutputStream.java:159)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmt.b(SourceFile:270)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at bmt.a(SourceFile:251)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at cct.c(SourceFile:42)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at cct.run(SourceFile:35)
[02:03:08] [File IO Thread/INFO]: [STDERR]: at java.lang.Thread.run(Thread.java:748)

wobst.michael 2018-08-30T16:10:43Z

Please check if that's still an issue in Minecraft 1.13.1.

bunny 2018-08-31T08:26:06Z

minecraft@bunny:~ % ./start.sh
[17:23:13] [main/WARN]: Ambiguity between arguments [teleport, destination] and [teleport, targets] with inputs: [Player, 0123, @e, dd12be42-52a9-4a91-a8a1-11c01849e498]
[17:23:13] [main/WARN]: Ambiguity between arguments [teleport, location] and [teleport, destination] with inputs: [0.1 -0.5 .9, 0 0 0]
[17:23:13] [main/WARN]: Ambiguity between arguments [teleport, location] and [teleport, targets] with inputs: [0.1 -0.5 .9, 0 0 0]
[17:23:13] [main/WARN]: Ambiguity between arguments [teleport, targets] and [teleport, destination] with inputs: [Player, 0123, dd12be42-52a9-4a91-a8a1-11c01849e498]
[17:23:13] [main/WARN]: Ambiguity between arguments [teleport, targets, location] and [teleport, targets, destination] with inputs: [0.1 -0.5 .9, 0 0 0]
[17:23:13] [main/INFO]: Loaded 0 recipes
[17:23:13] [main/INFO]: Loaded 0 advancements
[17:23:13] [Server thread/INFO]: Starting minecraft server version 1.13.1
[17:23:13] [Server thread/INFO]: Loading properties
[17:23:13] [Server thread/INFO]: Default game type: SURVIVAL
[17:23:13] [Server thread/INFO]: Generating keypair
[17:23:13] [Server thread/INFO]: Starting Minecraft server on 10.0.0.8:25565
[17:23:13] [Server thread/INFO]: Using default channel type
[17:23:13] [Server thread/INFO]: Preparing level "July2018"

Still there.

The spurious errors, however, have disappeared.

kumasasa 2018-09-01T01:36:33Z

In the latest log snippet there is nothig of "Permission denied".

bunny 2018-09-02T03:25:05Z

Correct. No spurious errors or permission denieds after 3 days of running. So you fixed one of the bugs.