Errors in server log with minor server meltdown

18.03.2019: Ein neuer Hotfix (0.9.5.4) ist nun verfügbar! Multiplayer Server müssen entsprechend geupdated werden

  • Errors in server log with minor server meltdown

    Okay I am back again with server hickup.
    Got so bad I had to restart the server manually again.
    About 4 hours since last restart.

    Might remove some of the plugins to see if that helps, but other than that I am out of ideas.
    Will - as usual - appreciate any help.

    Got this from the log where there seems to be errors and a warning right before....
    I removed some chat and player names.

    2019/01/20 09:18 AM G.c
    SEVERE: Invalid GlobalItemID for player yyyyname (ItemID: 740)
    2019/01/20 09:18 AM K.d
    INFO: Player yyyyname illegal state: ItemPickup: Invalid GlobalItemID 740
    Player playername mount not set (1430)!
    Player playername mount not set (1430)!
    Player playername mount not set (1430)!
    Player playername mount not set (1430)!
    Load WorldPartSQLite 1 -6...
    Return WorldPartSQLite 1 -6
    Worldpart generating...
    Player playername mount not set (1430)!
    Player playername mount not set (1430)!
    2019/01/20 09:19 AM: Server Validate Auth Ticket Response: 11000010af1b71f - 11000010af1b71f AuthTicketCanceled
    CLIENT 10 CHANGE STATE: Connected -> Disconnecting
    CLIENT 10 CHANGE STATE: Disconnecting -> Disconnected
    REMOVE: de.jiw.network.server.session.ClientConnection@97026c - 10 -
    7
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50374
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50308
    2019/01/20 09:19 AM G.f
    INFO: [TcpSessionHandler ChannelInactive] /120.29.104.200:50341
    Delete Client ID: 10
    [EndAuthSession] 183613215
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50275
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:20827 de.jiw.network.server.session.TcpSessionHandler@89145d PORT:5700
    /120.29.104.200
    CLIENT 13 CHANGE STATE: Disconnected -> ConnectingTCP
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50443 de.jiw.network.server.session.TcpSessionHandler@5070d9 PORT:5701
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50476 de.jiw.network.server.session.TcpSessionHandler@1cd913a PORT:5702
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50509 de.jiw.network.server.session.TcpSessionHandler@61f134 PORT:5703
    CLIENT 13 CHANGE STATE: ConnectingTCP -> ConnectingUDP
    CLIENT 13 CHANGE STATE: ConnectingUDP -> Connected
    2019/01/20 09:19 AM G.f
    INFO: Client Connected ID: 13 IP: 120.29.104.200
    PLAYER REQUEST AUTHENTICATION 13
    [BeginAuthSession] 183613215 - [20, 0, 0, 0, -123, -43, 117, 12, -29, -21, 98, -63, 31, -73, -15, 10, 1, 0, 16, 1, 51, 49, 68, 92, 24, 0, 0, 0, 1, 0, 0, 0, 2, 0, 0, 0, -56, 104, 29, 120, 0, 0, 0, 0, 117, 72, 103, 0, 4, 0, 0, 0, -78, 0, 0, 0, 50, 0, 0, 0, 4, 0, 0, 0, 31, -73, -15, 10, 1, 0, 16, 1, -16, -15, 4, 0, 52, 100, 29, 120, 26, 100, -88, -64, 0, 0, 0, 0, -25, -107, 63, 92, 103, 69, 91, 92, 1, 0, -19, -57, 0, 0, 0, 0, 0, 0, 8, 87, -73, 40, 113, -7, -9, -47, 70, 42, -21, -119, -12, -25, 42, -16, 36, -10, 1, 66, -7, -1, 83, 126, 21, -69, -91, 124, -53, 80, -92, -90, -102, 81, -15, 19, -77, 53, -76, -119, -126, -118, -1, 108, 86, 29, 70, -1, 64, 78, -10, -1, 105, -51, -91, -27, -87, 14, 15, 118, 61, -115, -120, -51, 3, -43, 40, 74, 114, 89, 35, 30, 19, 123, 75, 57, -89, -23, 20, 16, 33, 16, 80, -48, 94, 7, 25, -14, -72, 107, -81, -25, -31, 6, -34, -38, -102, 83, 119, 96, -73, 51, -10, -72, -92, -107, 124, -55, -25, -100, -78, 80, 27, -37, 26, 7, -16, 106, 111, 32, 41, -115, -106, 14, -99, -106, 26, -80]
    Client 13 send ReturnAuthenticationMessage
    2019/01/20 09:19 AM: Server Validate Auth Ticket Response: 11000010af1b71f - 11000010af1b71f OK
    PLAYER CONNECT: playername
    LOADPLAYER: playername (76561198143878943)
    CLIENT 13 CHANGE STATE: Connected -> Disconnecting2019/01/20 09:20 AM: Server Validate Auth Ticket Response: 11000010af1b71f - 11000010af1b71f AuthTicketCanceled
    CLIENT 13 CHANGE STATE: Disconnecting -> Disconnected

    REMOVE: de.jiw.network.server.session.ClientConnection@1cf5ed - 13 -
    7
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50443
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50509
    2019/01/20 09:20 AM G.f
    INFO: Delete Client ID: 13
    [EndAuthSession] 183613215
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50476
    [TcpSessionHandler ChannelInactive] /120.29.104.200:20827
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50535 de.jiw.network.server.session.TcpSessionHandler@10c41b6 PORT:5700
    /120.29.104.200
    CLIENT 14 CHANGE STATE: Disconnected -> ConnectingTCP
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50601 de.jiw.network.server.session.TcpSessionHandler@64c0be PORT:5702
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50568 de.jiw.network.server.session.TcpSessionHandler@686aa9 PORT:5701
    [TcpSessionHandler] NEW Client Connection from:/120.29.104.200:50634 de.jiw.network.server.session.TcpSessionHandler@14bc6cd PORT:5703
    CLIENT 14 CHANGE STATE: ConnectingTCP -> ConnectingUDP
    CLIENT 14 CHANGE STATE: ConnectingUDP -> Connected
    2019/01/20 09:20 AM G.f
    INFO: Client Connected ID: 14 IP: 120.29.104.200
    PLAYER REQUEST AUTHENTICATION 14
    [BeginAuthSession] 183613215 - [20, 0, 0, 0, 126, -65, -23, 14, -14, -45, 21, 86, 31, -73, -15, 10, 1, 0, 16, 1, 51, 49, 68, 92, 24, 0, 0, 0, 1, 0, 0, 0, 2, 0, 0, 0, -56, 104, 29, 120, 0, 0, 0, 0, -90, -16, 103, 0, 5, 0, 0, 0, -78, 0, 0, 0, 50, 0, 0, 0, 4, 0, 0, 0, 31, -73, -15, 10, 1, 0, 16, 1, -16, -15, 4, 0, 52, 100, 29, 120, 26, 100, -88, -64, 0, 0, 0, 0, -25, -107, 63, 92, 103, 69, 91, 92, 1, 0, -19, -57, 0, 0, 0, 0, 0, 0, 8, 87, -73, 40, 113, -7, -9, -47, 70, 42, -21, -119, -12, -25, 42, -16, 36, -10, 1, 66, -7, -1, 83, 126, 21, -69, -91, 124, -53, 80, -92, -90, -102, 81, -15, 19, -77, 53, -76, -119, -126, -118, -1, 108, 86, 29, 70, -1, 64, 78, -10, -1, 105, -51, -91, -27, -87, 14, 15, 118, 61, -115, -120, -51, 3, -43, 40, 74, 114, 89, 35, 30, 19, 123, 75, 57, -89, -23, 20, 16, 33, 16, 80, -48, 94, 7, 25, -14, -72, 107, -81, -25, -31, 6, -34, -38, -102, 83, 119, 96, -73, 51, -10, -72, -92, -107, 124, -55, -25, -100, -78, 80, 27, -37, 26, 7, -16, 106, 111, 32, 41, -115, -106, 14, -99, -106, 26, -80]
    Client 14 send ReturnAuthenticationMessage
    2019/01/20 09:20 AM: Server Validate Auth Ticket Response: 11000010af1b71f - 11000010af1b71f OK
    PLAYER CONNECT: playername
    LOADPLAYER: playername (76561198143878943)

    CLIENT 14 CHANGE STATE: Connected -> Disconnecting2019/01/20 09:20 AM: Server Validate Auth Ticket Response: 11000010af1b71f - 11000010af1b71f AuthTicketCanceled
    CLIENT 14 CHANGE STATE: Disconnecting -> Disconnected

    2019/01/20 09:20 AM JIW-Network
    WARNING: Endpoint is null or invalid! null - null
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50568
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50601
    REMOVE: de.jiw.network.server.session.ClientConnection@acbe2c - 14 -
    7
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50634
    2019/01/20 09:20 AM G.f
    INFO: Delete Client ID: 14
    [EndAuthSession] 183613215
    [TcpSessionHandler ChannelInactive] /120.29.104.200:50535
    ClientState Disconnecting: No message sent
    CLIENT 4 CHANGE STATE: Connected -> DisconnectingClientState Disconnecting: No message sent

    2019/01/20 09:21 AM: Server Validate Auth Ticket Response: 11000011303d7d4 - 11000011303d7d4 AuthTicketCanceled
    CLIENT 4 CHANGE STATE: Disconnecting -> Disconnected
    REMOVE: de.jiw.network.server.session.ClientConnection@1d644a4 - 4 -
    6
    [TcpSessionHandler ChannelInactive] /69.6.17.102:55884
    [TcpSessionHandler ChannelInactive] /69.6.17.102:55754
    [TcpSessionHandler ChannelInactive] /69.6.17.102:55819
    2019/01/20 09:21 AM G.f
    INFO: Delete Client ID: 4
    [EndAuthSession] 319018964
    [TcpSessionHandler ChannelInactive] /69.6.17.102:55689
    clear temporary voxel maps
    ... (4, 0) Chunks saved to DB!

    clear temporary voxel maps
    clear temporary lod maps
    ... (23, 9) Chunks saved to DB!
    [09:21] Stine: [#FFFFFF]did you guys just experience a hickup on the server?

    2019/01/20 09:21 AM G.c
    SEVERE: Invalid GlobalItemID for player yyyyname (ItemID: 799)
    2019/01/20 09:21 AM K.d
    INFO: Player yyyyname illegal state: ItemPickup: Invalid GlobalItemID 799
    clear temporary voxel maps
    [09:22] Stine: [#FFFFFF]hmmm....
    clear temporary voxel maps
    clear temporary voxel maps

    clear temporary voxel maps
    clear temporary lod maps
    clear temporary voxel maps
  • Well, the server stopped responding as in rendering (I got stuck at the end of the world), actions not possible as in the f-key unfunctional. It first happened as just as hickup where it got back to normal again within maybe a minute. But then it became impossible to do anything, people had to disconnect and I restarted the server, which fixed the problem.

    There has been some issues with server stability lately, but it was an odd and quite serious one, so I checked the logs. When I saw SEVERE etc I thought it looked bad and might be a more fundamental issue. If that is not the case (and it sounds like it isn't) it is probably just a plugin issue. I have already removed one plugin which seems to have worked, although the testing period has been a little too short to be sure.

    I have sent you the logs as requested, but if you would rather spend your time on something else I won't blame you ;)

    Thanks for being so willing to help though :)