[MC-114961] Multiplayer players get kicked on pickup first item for advancement

When any player joins a LAN world/server and picks up any block that unlocks a new advancement, the player gets kicked from the world.
When the player joins the LAN world/server again, everything is fine. The block is in the inventory and new advancements can be unlocked without getting kicked.

The player who is hosting the LAN world or the server won't crash when picking up a block that unlocks a new advancement.

Also, when the LAN/server player picks up any block that doesn't unlock an advancement, like dirt, nothing happens and the player stays on the server.

The exception a NullPointerException. I couldn't find any logs.

Steps to reproduce:

Create new world
Open for LAN (Survival, Cheats OFF)
OR create fresh server
Join from other PC/account
Pick up any block that unlocks an advancement
Player gets kicked (crashes)
Join LAN world/server again, everything is fine

Linked issues

is duplicated by 2

MC-115127 Player kicked on multiplayer when when unlocking crafting recipes Resolved

MC-115290 Kicked from server after getting wood on new snapshot Resolved

relates to 2

MC-115392 Only host can unlock advancements on LAN Resolved

MC-114883 No recipes unlocking on server Resolved

Attachments

latest (1st account, host.log

latest (2nd account).log

Comments 9

Johnny van Manen 2017-03-30T15:24:09Z

Reproduced and confirmed.

Jochem Broekhoff 2017-03-30T16:45:03Z

I don't see why this issue relates to MC-114883

[Mod] Neko 2017-03-30T20:32:31Z

Thank you for your report!
However, this issue has been temporarily closed as Awaiting Response.

We do not have enough information to find the cause of this issue.
Please attach the crash report (.minecraft/crash-reports/crash-<DATE>-client.txt) here.

Once attached, the report will be reopened automatically.

Quick Links:
📓 Issue Guidelines – 💬 Community Support – 📧 Customer Support – 📖 Game Wiki

Johnny van Manen 2017-03-30T20:56:32Z

Weird enough, no crash reports are being generated.. I included the logs, but I think it doesn't contain usefull information..
I repeat: No crash reports are being generated..

Jochem Broekhoff 2017-03-31T06:01:47Z

Yes, same for the hosting person.

[Bot] Arisa 2017-03-31T11:26:00Z

Please do not mark unreleased versions as affected.
You don't have access to them yet.

Johnny van Manen 2017-03-31T11:32:14Z

In 17w13b also affects SERVER, not only LAN worlds

Nigel Todman 2017-03-31T12:29:48Z

I can confirm. Not just LAN. WAN as well.

I experienced something very similar upon upgrading my server to 17w13b

The server didnt crash, but the client is forcibly disconnected. Client isnt technically crashed either.

Disconnection message is:

[07:57:41] [Server thread/INFO]: YT_Veritas0923 lost connection: TranslatableComponent{key='disconnect.genericReason', args=[Internal Exception: java.io.IOException: An established connection was aborted by the software in your host machine], siblings=[], style=Style{hasParent=false, color=null, bold=null, italic=null, underlined=null, obfuscated=null, clickEvent=null, hoverEvent=null, insertion=null}}

It complained about 'malformed json' I believe.

Update:

I managed to capture this on video during my recent livestream testing the snapshot, at the 41m43s mark i connect and shortly after break a block and am promptly disconnected.

I had just finished deleting all playerdata/stats/advancements etc for that UUID prior to connecting (so it was like a new player)

https://www.twitch.tv/videos/132454225

null 2017-04-05T22:07:01Z

Can confirm. Here's the server and client logs with debug messages enabled:

Server log (17w14a) with debug messages

[15:02:38] [User Authenticator #1/INFO]: UUID of player __null is d41b28a1-a216-441c-9743-5da0e80b4425
[15:02:38] [Server thread/INFO]: __null[/127.0.0.1:62568] logged in with entity id 400 at (208.25815869517018, 80.0, 196.746439391767)
[15:02:38] [Server thread/DEBUG]: Set listener of gd@1e00fd86 to ob@4ee6166a
[15:02:38] [Server thread/DEBUG]: Disabled auto read
[15:02:38] [Netty Server IO #1/DEBUG]: Enabled auto read
[15:02:38] [Server thread/INFO]: __null joined the game
[15:02:38] [Netty Server IO #1/DEBUG]: java.io.IOException: Broken pipe
[15:02:38] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:38] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:38] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
... last line repeated 18 more times...
[15:02:39] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:39] [Server thread/INFO]: __null lost connection: TextComponent{text='Disconnected', siblings=[], style=Style{hasParent=false, color=null, bold=null, italic=null, underlined=null, obfuscated=null, clickEvent=null, hoverEvent=null, insertion=null}}
[15:02:39] [Server thread/INFO]: __null left the game
[15:02:39] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:39] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:39] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
... last line repeated 480 more times...
[15:02:39] [Netty Server IO #1/DEBUG]: java.nio.channels.ClosedChannelException
[15:02:43] [Server thread/INFO]: Stopping the server
[15:02:43] [Server thread/INFO]: Stopping server
[15:02:43] [Server thread/INFO]: Saving players
[15:02:43] [Server thread/INFO]: Saving worlds
[15:02:43] [Server thread/INFO]: Saving chunks for level 'world'/Overworld
[15:02:43] [Server thread/INFO]: Saving chunks for level 'world'/Nether
[15:02:43] [Server thread/INFO]: Saving chunks for level 'world'/The End
[15:02:43] [Server Shutdown Thread/INFO]: Stopping server

Client log (17w14a) with debug messages

[15:33:02] [Netty Client IO #0/DEBUG]: Set listener of gd@6e367573 to bqo@26458c90
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.lang.NullPointerException
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/40 (c) was larger than I expected, found 869 bytes extra whilst reading packet 40
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 114
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 97
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 102
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 116
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: The received encoded string buffer length is longer than maximum allowed (101 > 64)
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 105
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 115
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/47 (iv) was larger than I expected, found 119156 bytes extra whilst reading packet 47
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 105
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/1 (he) was larger than I expected, found 181565 bytes extra whilst reading packet 1
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 96
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/48 (iw) was larger than I expected, found 231398 bytes extra whilst reading packet 48
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: The received encoded string buffer length is longer than maximum allowed (101 > 64)
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 105
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 115
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/47 (iv) was larger than I expected, found 287760 bytes extra whilst reading packet 47
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 115
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 95
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 116
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 104
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 95
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 114
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 105
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/1 (he) was larger than I expected, found 543766 bytes extra whilst reading packet 1
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 96
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/38 (a) was larger than I expected, found 600280 bytes extra whilst reading packet 38
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 116
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: The received encoded string buffer length is longer than maximum allowed (101 > 64)
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 99
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 105
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 101
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 115
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Packet 0/47 (iv) was larger than I expected, found 657338 bytes extra whilst reading packet 47
[15:33:02] [Netty Client IO #0/DEBUG]: io.netty.handler.codec.DecoderException: java.io.IOException: Bad packet id 112
[15:33:03] [Chunk Batcher 4/DEBUG]: Stopping chunk worker due to interrupt
[15:33:03] [Chunk Batcher 5/DEBUG]: Stopping chunk worker due to interrupt
[15:33:03] [Chunk Batcher 6/DEBUG]: Stopping chunk worker due to interrupt
[15:33:03] [Chunk Batcher 7/DEBUG]: Stopping chunk worker due to interrupt