Eject

Server Lags

35 posts in this topic

I hope now a Dev from Code Club can help me out:

Now we had again a big lag for 2 minutes on the loginserver..

 

[06:50:05 AM] INFO com.wurmonline.server.creatures.Creature: Polled Creature id, 927675942840577, which took 1701.1559 millis.

[06:50:05 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 1.709 seconds

[06:50:05 AM] INFO com.wurmonline.server.Server: Elapsed time (1710ms) for this loop was more than 1 second so adding it to the lag count, which is now: 806

[06:51:09 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.258 seconds

[06:51:09 AM] INFO com.wurmonline.server.Server: Elapsed time (20259ms) for this loop was more than 1 second so adding it to the lag count, which is now: 826

[06:51:29 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.059 seconds

[06:51:29 AM] INFO com.wurmonline.server.Server: Elapsed time (20059ms) for this loop was more than 1 second so adding it to the lag count, which is now: 846

[06:51:40 AM] INFO com.wurmonline.server.console.CommandReader: Console reader exiting.

[06:51:50 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.622 seconds

[06:51:50 AM] INFO com.wurmonline.server.Server: Elapsed time (20623ms) for this loop was more than 1 second so adding it to the lag count, which is now: 866

[06:52:12 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 21.73 seconds

[06:52:12 AM] INFO com.wurmonline.server.Server: Elapsed time (21731ms) for this loop was more than 1 second so adding it to the lag count, which is now: 887

[06:52:31 AM] INFO com.wurmonline.server.ServerEntry: Drachenfels is no longer available.

[06:52:36 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 23.715 seconds

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 1 since it could not be found

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 2 since it could not be found

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 4 since it could not be found

[06:52:36 AM] INFO com.wurmonline.communication.SocketServer: Accepted player connection: Socket[addr=/178.191.147.71,port=59739,localport=15000]

[06:52:36 AM] INFO com.wurmonline.server.Server: Elapsed time (23722ms) for this loop was more than 1 second so adding it to the lag count, which is now: 910

[06:52:40 AM] INFO com.wurmonline.server.Server: current mem in use: 3803M free mem: 933M Max mem: 12495M

player count: 3

bytes in: 0 bytes out: 0 total in: 32465927 total out: 205684361

Server uptime: 85800 seconds. Unanswered questions:0

[06:52:56 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.299 seconds

[06:52:56 AM] INFO com.wurmonline.server.Server: Elapsed time (20299ms) for this loop was more than 1 second so adding it to the lag count, which is now: 930

[06:52:56 AM] INFO com.wurmonline.server.steam.SteamHandler: Client was Authenticated

[06:52:56 AM] INFO com.wurmonline.server.steam.SteamHandler: Client was NOT Authenticated

[06:53:18 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 22.267 seconds

[06:53:18 AM] INFO com.wurmonline.server.Server: Elapsed time (22268ms) for this loop was more than 1 second so adding it to the lag count, which is now: 952

[06:53:30 AM] INFO com.wurmonline.server.ServerEntry: Drachenfels is now available.

[06:53:30 AM] INFO com.wurmonline.server.epic.EpicEntity: Fo visits the Jackal.

[06:53:30 AM] INFO com.wurmonline.server.epic.EpicEntity: Jackal is meeting with Fo.

[06:53:35 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 17.043 seconds

[06:53:35 AM] INFO com.wurmonline.server.Server: Elapsed time (17044ms) for this loop was more than 1 second so adding it to the lag count, which is now: 969

[06:53:39 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 3.401 seconds

[06:53:39 AM] INFO com.wurmonline.server.Server: Elapsed time (3401ms) for this loop was more than 1 second so adding it to the lag count, which is now: 972

[06:53:39 AM] INFO com.wurmonline.server.webinterface.WebInterfaceImpl: serverId:3958 kingdom id 4 exists=true

[06:53:39 AM] INFO com.wurmonline.server.webinterface.WebInterfaceImpl: Drachenfels kingdom id 4 exists=true

Share this post


Link to post
Share on other sites
On 10/20/2017 at 10:11 AM, bigsteve said:

I doubt more ram will help.

Ages of Urath had 2 maps and peek times 50, average 30.

When the 3rd map was add, lag spike incredibly  so much the 3rd map was retired.

I think you are suffering from thread thrashing.

Sadly i do not have access to Ages of Urath log in server file any more, the copy of it i downloaded only the 2nd Laoks map folder survived. So i can advise  on how the .ini was set-up.

If there is any additional optimizing I can do to the ini I'd be interested. I use the scheduled executor for most of the important stuff but things like number of executor threads (20) or # of updates (500) if there are better recommendations I'm all ears. Or things like are check db tables  or optimize db tables something that's a good idea. I manually go thru the databases periodically to clean them up was leary about letting an automated process do it for me. But from the wiki the check and optimize db tables descriptors are too vague for me.

Edited by LifesaverM

Share this post


Link to post
Share on other sites

Ill take a look at the files for ages of urath again to so if there is a wurm.ini file nd if its been changed.

 

Not sure but if too many proccesses are pushed to the executor thread that may be the cause of the lag, due the thread thrashing.

you have to find the balance. Number of threads for server processes and number of threads for executor processs.

Razor did tell me a formula he found out that had which had the relation of

Total core threads to server process threads to executor process threads.

I really can not remember what he said it was.

 

 

EDIT: i thought i Posted when i was on my Tablet LOLZ got home and saw nothing was Posted, to hit post.

 

After some brain storming and trying to remember what Razor said, he "may" of said set SCHEDULED_EXECUTOR_SERVICE_NUMBER_OF_THREADS to half the number of threads the machine has. See if that helps..

And i think try not to use executor to do extra, reset it back to default and try thread thing first.

i do hope only 1 Server Runs on the each machine, as running multiple servers will cause the thread thrashing to be exacerbated.

Virtual Machining them will have the same effect i think. (possible 2 servers is the Limit with less that 30 players).

Edited by bigsteve

Share this post


Link to post
Share on other sites
On 23.10.2017 at 7:56 AM, Eject said:

I hope now a Dev from Code Club can help me out:

Now we had again a big lag for 2 minutes on the loginserver..

 

[06:50:05 AM] INFO com.wurmonline.server.creatures.Creature: Polled Creature id, 927675942840577, which took 1701.1559 millis.

[06:50:05 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 1.709 seconds

[06:50:05 AM] INFO com.wurmonline.server.Server: Elapsed time (1710ms) for this loop was more than 1 second so adding it to the lag count, which is now: 806

[06:51:09 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.258 seconds

[06:51:09 AM] INFO com.wurmonline.server.Server: Elapsed time (20259ms) for this loop was more than 1 second so adding it to the lag count, which is now: 826

[06:51:29 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.059 seconds

[06:51:29 AM] INFO com.wurmonline.server.Server: Elapsed time (20059ms) for this loop was more than 1 second so adding it to the lag count, which is now: 846

[06:51:40 AM] INFO com.wurmonline.server.console.CommandReader: Console reader exiting.

[06:51:50 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.622 seconds

[06:51:50 AM] INFO com.wurmonline.server.Server: Elapsed time (20623ms) for this loop was more than 1 second so adding it to the lag count, which is now: 866

[06:52:12 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 21.73 seconds

[06:52:12 AM] INFO com.wurmonline.server.Server: Elapsed time (21731ms) for this loop was more than 1 second so adding it to the lag count, which is now: 887

[06:52:31 AM] INFO com.wurmonline.server.ServerEntry: Drachenfels is no longer available.

[06:52:36 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 23.715 seconds

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 1 since it could not be found

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 2 since it could not be found

[06:52:36 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 4 since it could not be found

[06:52:36 AM] INFO com.wurmonline.communication.SocketServer: Accepted player connection: Socket[addr=/178.191.147.71,port=59739,localport=15000]

[06:52:36 AM] INFO com.wurmonline.server.Server: Elapsed time (23722ms) for this loop was more than 1 second so adding it to the lag count, which is now: 910

[06:52:40 AM] INFO com.wurmonline.server.Server: current mem in use: 3803M free mem: 933M Max mem: 12495M

player count: 3

bytes in: 0 bytes out: 0 total in: 32465927 total out: 205684361

Server uptime: 85800 seconds. Unanswered questions:0

[06:52:56 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 20.299 seconds

[06:52:56 AM] INFO com.wurmonline.server.Server: Elapsed time (20299ms) for this loop was more than 1 second so adding it to the lag count, which is now: 930

[06:52:56 AM] INFO com.wurmonline.server.steam.SteamHandler: Client was Authenticated

[06:52:56 AM] INFO com.wurmonline.server.steam.SteamHandler: Client was NOT Authenticated

[06:53:18 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 22.267 seconds

[06:53:18 AM] INFO com.wurmonline.server.Server: Elapsed time (22268ms) for this loop was more than 1 second so adding it to the lag count, which is now: 952

[06:53:30 AM] INFO com.wurmonline.server.ServerEntry: Drachenfels is now available.

[06:53:30 AM] INFO com.wurmonline.server.epic.EpicEntity: Fo visits the Jackal.

[06:53:30 AM] INFO com.wurmonline.server.epic.EpicEntity: Jackal is meeting with Fo.

[06:53:35 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 17.043 seconds

[06:53:35 AM] INFO com.wurmonline.server.Server: Elapsed time (17044ms) for this loop was more than 1 second so adding it to the lag count, which is now: 969

[06:53:39 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 3.401 seconds

[06:53:39 AM] INFO com.wurmonline.server.Server: Elapsed time (3401ms) for this loop was more than 1 second so adding it to the lag count, which is now: 972

[06:53:39 AM] INFO com.wurmonline.server.webinterface.WebInterfaceImpl: serverId:3958 kingdom id 4 exists=true

[06:53:39 AM] INFO com.wurmonline.server.webinterface.WebInterfaceImpl: Drachenfels kingdom id 4 exists=true

 

 

I still think that you have some zone on your server with an incredible amount of items on one tile, or something related.

 

A normal server would never act that laggy. We have 300 player peaks on Sklotopolis, lag is coming no where close to this madness and we are using less RAM.

Edited by Sklo:D

Share this post


Link to post
Share on other sites
On 25.10.2017 at 6:46 PM, Sklo:D said:

I still think that you have some zone on your server with an incredible amount of items on one tile, or something related.

 

Is there a way to find this tile? we also use bag of holding, i hope this is not the problem.

 

now i got a bigger lag :(

 

[11:00:11 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 43.403 seconds

[11:00:11 AM] INFO com.wurmonline.server.Server: Elapsed time (43405ms) for this loop was more than 1 second so adding it to the lag count, which is now: 50

[11:00:54 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 42.753 seconds

[11:00:54 AM] INFO com.wurmonline.server.Server: Elapsed time (42753ms) for this loop was more than 1 second so adding it to the lag count, which is now: 92

[11:01:36 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 42.061 seconds

[11:01:36 AM] INFO com.wurmonline.server.Server: Elapsed time (42063ms) for this loop was more than 1 second so adding it to the lag count, which is now: 134

[11:02:13 AM] INFO com.wurmonline.server.Server: Lag detected at Zones.pollnextzones (0.5): 37.339 seconds

[11:02:13 AM] INFO com.wurmonline.server.Server: Elapsed time (37340ms) for this loop was more than 1 second so adding it to the lag count, which is now: 171

[11:02:14 AM] INFO com.wurmonline.server.epic.EpicServerStatus: Deleting last mission for deity 4 since it could not be found

[11:02:43 AM] INFO com.wurmonline.server.Server: Bertl moves down to 22. Was lagging 28 seconds with a peak of 480 moves.

[11:03:45 AM] INFO com.wurmonline.server.Server: current mem in use: 5177M free mem: 2688M Max mem: 12495M

Share this post


Link to post
Share on other sites
On 24.10.2017 at 1:32 AM, bigsteve said:

After some brain storming and trying to remember what Razor said, he "may" of said set SCHEDULED_EXECUTOR_SERVICE_NUMBER_OF_THREADS to half the number of threads the machine has. See if that helps..

And i think try not to use executor to do extra, reset it back to default and try thread thing first.

i do hope only 1 Server Runs on the each machine, as running multiple servers will cause the thread thrashing to be exacerbated.

Virtual Machining them will have the same effect i think. (possible 2 servers is the Limit with less that 30 players).

Hello bigsteve

 

I have 3 maps running on one machine (there is no other customer on it)

My entry is:

SCHEDULED_EXECUTOR_SERVICE_NUMBER_OF_THREADS=20

but i dont know what you mean with the half number of threads the machine has :(

 

Eject

Share this post


Link to post
Share on other sites

you need to find out what CPU the Machine has, use Windows Task manager, here is mine home PC

5fd6e70fbf.png

The Logical Processors is the number of threads.

 

 

1 person likes this

Share this post


Link to post
Share on other sites

Ah ok

So i have 8 processors. shall i type in SCHEDULED_EXECUTOR_SERVICE_NUMBER_OF_THREADS=4 at all 3 servers or only at the server with the lag?

Best is i just try it =)

 

Thank you Steve

Eject

Edited by Eject

Share this post


Link to post
Share on other sites

Does that map you added have a volcano? I haven't been in the code for a while now cause RL priority took over but last time I was in it lava fiends and spiders where coded to spawn on lava tiles and the sad news they where also not immune to the damage when standing on said tile so they would die in 60 secs after spawning and then the system would say this mob has not meet it's limit and span again till eventually you have x amount of corpses in the volcano. Corpses take a good amount of time before despawning yet it only take 60 secs for it to spawn and die.

Share this post


Link to post
Share on other sites

Hello Arkonik

 

There is no volcano and the servers are newbie friendly so no lava fiends.

Yesterday as i had the lag on the one neighbourserver (on this map was 3 players online at the time), i asked the players what they was doing now.

They told me just one was taking 4 nails from the forge, the other was riding and the last just mining rocks. I hopes i can find out why this lag was coming.

 

Eject

Share this post


Link to post
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!


Register a new account

Sign in

Already have an account? Sign in here.


Sign In Now