search

DarkflameUniverse / DarkflameServer

The main repository for the Darkflame Universe Server Emulator project.
https://www.darkflameuniverse.org/
GNU Affero General Public License v3.0
642 stars 172 forks source link

BUG: kick to login when leaving Nimbus Station after all missions are done #1498

Closed leolion3 closed 6 months ago

leolion3 commented 6 months ago

Make sure you've done the following:

DarkflameServer Version

e729c7f

Issue Description

Im hosting a DLU multiplayer community server for a couple people, one of them reported the issue to me. I've joined them and operated the server logs to reproduce the issue.

After finishing all Nimbus Station missions, the client attempts to change the world (doenst really matter which rocket launchpad they use). When they attempt to do so, they are kicked out of the server. To other players it seems as if the character is holding his rocket before disappearing due to the disconnection.

Heres a server log from the event:

Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:27 WorldServer.cpp:153] Starting World server...
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:27 WorldServer.cpp:154] Version: 1.1.1
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:27 WorldServer.cpp:155] Compiled on: Mon Jan  8 19:25:24 2024
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dServer.cpp:70] World Server is listening on localhost:3012 with encryption: 1, running zone 1400 / 4
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dNavMesh.cpp:24] Navmesh loaded successfully!
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dpWorld.cpp:29] Physics world initialized!
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dZoneManager.cpp:21] Preparing zone: 1400/4/0
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dZoneManager.cpp:49] Creating zone control object 2365
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dZoneManager.cpp:221] Loading WorldConfig into memory
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dZoneManager.cpp:291] Loaded WorldConfig into memory
Feb 13 21:30:28 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:28 dZoneManager.cpp:69] Zone prepared in: 410 ms
Feb 13 21:30:33 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:27 MasterServer.cpp:729] Prepping zone 1400
Feb 13 21:30:33 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:27 InstanceManager.cpp:32] Searching for an instance for mapID 1400/0
Feb 13 21:30:33 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:27 InstanceManager.cpp:66] Created new instance: 1400/4/0 with min/max 33/43
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:23 WorldServer.cpp:1295] LeoLion: ok go
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:27 CharacterComponent.cpp:375] Time since last save: 128
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:27 Character.cpp:324] 238:MuscularPreciousWerewolf Saved character to Database in: 0.063628s
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 CharacterComponent.cpp:375] Time since last save: 2
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 Character.cpp:324] 238:MuscularPreciousWerewolf Saved character to Database in: 0.009755s
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 WorldServer.cpp:813] Deleting player 1152921508901814510
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 Player.cpp:265] Deleted player
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 CharacterComponent.cpp:375] Time since last save: 0
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 Character.cpp:324] 238:MuscularPreciousWerewolf Saved character to Database in: 0.002038s
Feb 13 21:30:34 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:29 UserManager.cpp:148] Deleted user 21
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:33 Behavior.cpp:432] Failed to load behavior with id (0)!
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:35 MovingPlatformComponent.cpp:66] Path not found:
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:35 MovingPlatformComponent.cpp:66] Path not found:
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:36 dServer.cpp:119] Established connection to master, zone (1400), instance (4)
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:36 WorldServer.cpp:538] Established connection to chat, zone(1400), instance (4)
Feb 13 21:30:43 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:36 WorldServer.cpp:510] Finished loading world with zone (1400), ready up!
Feb 13 21:30:47 instance-20211214-1406 runserver[2761023]: [13-02-24 21:30:28 ChatServer.cpp:190] A server is connecting, awaiting user list.
Feb 13 21:30:47 instance-20211214-1406 runserver[2761023]: [13-02-24 21:30:29 PlayerContainer.cpp:91] Removed user: 1152921508901814510
Feb 13 21:30:48 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:36 MasterServer.cpp:549] Received server info, instance: 4 port: 3012
Feb 13 21:30:48 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:36 MasterServer.cpp:705] Got world ready 1400 4
Feb 13 21:30:48 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:36 MasterServer.cpp:714] Ready zone 1400
Feb 13 21:30:57 instance-20211214-1406 runserver[2761216]: [13-02-24 21:30:53 WorldServer.cpp:766] Got new session alert for user my_friends_username
Feb 13 21:30:57 instance-20211214-1406 runserver[2761216]: [13-02-24 21:30:53 WorldServer.cpp:770] But they're not logged in?
Feb 13 21:30:58 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:54 WorldServer.cpp:766] Got new session alert for user my_friends_username
Feb 13 21:30:58 instance-20211214-1406 runserver[2762589]: [13-02-24 21:30:54 WorldServer.cpp:770] But they're not logged in?
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:458] Received zone transfer req
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 InstanceManager.cpp:32] Searching for an instance for mapID 0/0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:477] Instance: 0/0/0 -> 1
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:477] Instance: 1000/0/1 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:477] Instance: 1200/0/2 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:477] Instance: 1900/0/3 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:477] Instance: 1400/0/4 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:488] Responding to transfer request 6 for zone 0 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 InstanceManager.cpp:185] Sent affirmation request 6 to 0/0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:743] Got affirmation of transfer 6
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:751] Affirmation complete 6
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:53 MasterServer.cpp:576] Got sessionKey -1849721463 for user my_friends_username
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:54 MasterServer.cpp:584] Requesting session key for my_friends_username
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:458] Received zone transfer req
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 InstanceManager.cpp:32] Searching for an instance for mapID 1200/0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:477] Instance: 0/0/0 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:477] Instance: 1000/0/1 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:477] Instance: 1200/0/2 -> 1
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:477] Instance: 1900/0/3 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:477] Instance: 1400/0/4 -> 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:488] Responding to transfer request 6 for zone 1200 0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 InstanceManager.cpp:185] Sent affirmation request 6 to 1200/0
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:743] Got affirmation of transfer 6
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:751] Affirmation complete 6
Feb 13 21:31:03 instance-20211214-1406 runserver[2761017]: [13-02-24 21:30:59 MasterServer.cpp:584] Requesting session key for my_friends_username
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:53 WorldServer.cpp:766] Got new session alert for user my_friends_username
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:53 WorldServer.cpp:770] But they're not logged in?
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:59 WorldServer.cpp:743] Got affirmation request of transfer 6
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:59 WorldServer.cpp:697] User my_friends_username authenticated with correct key.
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:59 Character.cpp:85] Loaded xmlData for character MuscularPreciousWerewolf (238)!
Feb 13 21:31:04 instance-20211214-1406 runserver[2761163]: [13-02-24 21:30:59 User.cpp:41] Loaded 238 as it is the last used char
Feb 13 21:31:07 instance-20211214-1406 runserver[2761027]: [13-02-24 21:30:53 WorldServer.cpp:766] Got new session alert for user my_friends_username
Feb 13 21:31:07 instance-20211214-1406 runserver[2761027]: [13-02-24 21:30:53 WorldServer.cpp:770] But they're not logged in?
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:53 WorldServer.cpp:743] Got affirmation request of transfer 6
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 WorldServer.cpp:766] Got new session alert for user my_friends_username
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 WorldServer.cpp:770] But they're not logged in?
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 WorldServer.cpp:697] User my_friends_username authenticated with correct key.
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 Character.cpp:85] Loaded xmlData for character MuscularPreciousWerewolf (238)!
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 Character.cpp:337] Removed isLoggedIn flag from character 238:MuscularPreciousWerewolf, saving character to database
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 Character.cpp:85] Loaded xmlData for character HonestHeroicWerewolf (232)!
Feb 13 21:31:07 instance-20211214-1406 runserver[2761025]: [13-02-24 21:30:54 GameMessageHandler.cpp:52] Failed to find associated entity (288230376151711793), aborting GM: 1132, MODULE_ASSEMBLY_QUERY_DATA!

(*) While the log is a bit older, the issue still persists in DLU 2.2.0

Reproduction Steps

To reproduce, finish all Nimbus Station quests, then attempt to leave the area using the Nimbus Station rocket and any launchpad (perhaps that rocket is buggy).

Expected Behavior

The client should just get moved to another world, as is true for all other players on the server.

Environment

Server is running in an Oracle VM. The infrastructure and databases are set up correctly and all services are reachable. Players are able to play regularly, except for one player who finished all missions.

The server is running as a daemon with some automated scripts (link) - these have no effect on performance though.

EmosewaMC commented 6 months ago

Server logs will not help here. Please post your friends charxml instead or a charxml that had the issue.

EmosewaMC commented 6 months ago

As a safeguard, please ensure your client has ugc requests turned off as per the readme.

leolion3 commented 6 months ago

Disabling ugc services worked. Since its a new change it hadnt been incorporated for my clients.

Does that close the issue or are you planning on troubleshooting further?

EmosewaMC commented 6 months ago

that closes the issue. duplicate of #1277.

Also not being able to login after getting kicked to login is a duplicate issue of #154

you can solve the needing to reboot the client when getting kicked to login by renaming the folder with legouniverse.exe in it to client. This is a requirement and how the game was shipped in live.