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

    Hmm... what sort of "server meltdown" or hickup did you experience exactly?
    The log snippet indicates that there was some sort of desync going on, but usually just minor issues (according to the log) which could also be caused by a plugin... can you maybe send me the full log via PM? :)

    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 :)

Participate now!

Don’t have an account yet? Create a new account now and be part of our community!

Create Account Login