Long callback times and high Hard Drive usage.

For any problems with Dawn of Light website or game server, please direct questions and problems here.

Moderator: Support Team

Post a reply

Re: Long callback times and high Hard Drive usage.

Postby PlanarChaosRvrtwo » Thu Jul 06, 2017 5:14 am

I just removed this message

(leodagan answered so read my guess is waste of time)
Last edited by PlanarChaosRvrtwo on Thu Jul 06, 2017 6:32 am, edited 1 time in total.
DOL dint only gaved me the data to start my server,
it also gaved me 16 amazing years with nice peeps,
and now its on me to return the favor.
User avatar
PlanarChaosRvrtwo
Database Team
 
Posts: 517
Joined: Thu Jul 07, 2016 6:21 am
Top

Re: Long callback times and high Hard Drive usage.

Postby Leodagan » Thu Jul 06, 2017 5:53 am

Seriously...

Packet Handler is completely off-topic for this kind of troubles, especially for unhandled packets...

Packet ID 220 is Client To Server 0xDC "Message Filters", DOL don't use these packets (Server doesn't care about Client message filters, he still sends every message and client filters on his side)

The only way to trigger a massive Disk Usage is when the Database is overload, we don't have any piece of code that write to disk for runtime needs !


The RewardMob is not an official DOL core mechanism, by reading the code I suspect that other area of DOL are highly customized in this setup... it will be hard to provide any debug help... but you should look around the Database ! (even trying to log queries when the overload is triggering)
User avatar
Leodagan
Developer
 
Posts: 1350
Joined: Tue May 01, 2012 9:30 am
Website: https://daoc.freyad.net
Location: Lyon
Top

Re: Long callback times and high Hard Drive usage.

Postby PlanarChaosRvrtwo » Thu Jul 06, 2017 6:29 am

Kay thanks Leodagan tried my best but cant code ^^ So i just had to guess^^
DOL dint only gaved me the data to start my server,
it also gaved me 16 amazing years with nice peeps,
and now its on me to return the favor.
User avatar
PlanarChaosRvrtwo
Database Team
 
Posts: 517
Joined: Thu Jul 07, 2016 6:21 am
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Thu Jul 06, 2017 1:06 pm

I would expect the RewardMob code too, but then we went to a big camp (Lyonesse Goblins) that do NOT have the RewardMob code, and they gave the same error.
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Thu Jul 06, 2017 3:42 pm

Do you have custom code in your server, and can you confirm you are using the latest revision ?
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Thu Jul 06, 2017 11:17 pm

The RewardMob is custom code right?
And I'm using DOLSharp-1.9.7.3616.
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Fri Jul 07, 2017 12:00 am

RewardMob is custom code, have you considered maybe letting RewardMob drop a token and having a NPC that you can hand the token into then doing the calculations you have in that script?


Or try using else if?

Maybe a long way from what you need, but try and this way we both learn
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Fri Jul 07, 2017 1:09 pm

1.9.7.3617 is the latest release FYI

https://github.com/Dawn-of-Light/DOLSharp
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Fri Jul 07, 2017 1:19 pm

What should I do here?
Line 229-248.
It's missing a bracket somewhere, and the whole thing is getting commented.

Thanks :D

Code: Select all
private static readonly ILog log = LogManager.GetLogger(MethodBase.GetCurrentMethod().DeclaringType);



public override int MaxHealth => base.MaxHealth * 2;

/* public override int MaxSpeedBase
{
get
{
return 191 ;
}
set
{
m_maxSpeedBase = value;
}
}
*/
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Fri Jul 07, 2017 2:04 pm

If you mean removing comment
Code: Select all
public override short MaxSpeedBase
{
get
{
return 191 ;
}
set
{
m_maxSpeedBase = value;
}
}

Or leave as is but either way add a } to bottom of script
Last edited by Loki on Fri Jul 07, 2017 3:40 pm, edited 1 time in total.
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Fri Jul 07, 2017 2:45 pm

Yeah I found the brackets thing at the bottom, I just didn't say anything about it :P

Edit: Line 236 is giving me in error in the server, not allowing the server to load:
Code: Select all
public override int MaxHealth => base.MaxHealth * 2;
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Fri Jul 07, 2017 3:39 pm

Not easy with no VS on this device :(

Try again lol
Attachments
RewardMob.cs
(8.54 KiB) Downloaded 19 times
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Fri Jul 07, 2017 4:26 pm

Okay I'm online. Stand by.
Thanks for your help by the way.

I'm trying to get out of Win/Lin administration and get into programming, hopefully I can help this project for as long as possible. The DOL project that is. I've been playing on DOL servers off/on since 2005? Or so? I always wanted to host, and here I am.

#Edit:
Still occurring. At first I thought it wasn't, or not as much... but it was only a low lvl guy pulling slowly :D
I have 5 or so people online, and you can see it streaming back in.

HOWEVER - it doesn't seem AS bad yet... As far as hard drive usage. It's only spiking up to 50-70% usage.. Before they were maxing it out and crashing the zone.
Code: Select all
2017-07-07 13:49:44,529 - [RegionTime4] - INFO - DOL.GS.GamePlayer - Creamofmushroom saved!
2017-07-07 13:49:44,651 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 515ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006C4F2 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:49:48,263 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 307ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006D427 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Protector, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Protector, ID=11158, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:49:48,414 - [21] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=5kb/s (9MB) In=7pck/s (25K) Out=207pck/s (325K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2970 RegionTime1=34t/s (263) RegionTime2=29t/s (212) RegionTime3=0t/s (1557) RegionTime4=110t/s (842) CPU=17.0% DOL=13.1% pg/s=0.0 dsk/s=9.0
2017-07-07 13:49:48,909 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 325ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006D69A interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:49:54,142 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 613ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006E9EB interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:49:54,616 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 320ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006ECEC interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:49:57,391 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 284ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0006F7E8 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:02,020 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 321ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000709D7 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:03,618 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:50:03,648 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:50:03,659 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:50:05,987 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 308ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00071964 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:06,387 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 323ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00071AE5 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:18,429 - [17] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=4kb/s (10MB) In=7pck/s (25K) Out=145pck/s (329K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2968 RegionTime1=36t/s (260) RegionTime2=29t/s (207) RegionTime3=0t/s (1557) RegionTime4=120t/s (567) CPU=17.3% DOL=12.2% pg/s=0.0 dsk/s=21.3
2017-07-07 13:50:21,140 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 333ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0007547D interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:30,271 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 302ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00077848 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:34,701 - [23] - INFO - DOL.GS.WeatherManager - Weather Stopped in Region Atlantis (ID 73) CurrentPosition : 668200
[RegionWeather Region=73, Position=229376, Width=78361, Speed=488, Intensity=34, Diffusion=22354, DurationTime=899s, MinPosition=229376, MaxPosition=668185]
2017-07-07 13:50:34,800 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 307ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000789F6 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Protector, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Protector, ID=11158, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:35,726 - [RegionTime4] - INFO - DOL.GS.GamePlayer - Creamofmushroom saved!
2017-07-07 13:50:35,831 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 725ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00078C5A interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:39,319 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 311ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00079B99 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:40,575 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 426ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0007A00F interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:42,802 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 561ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0007A83A interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:48,454 - [21] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=3kb/s (10MB) In=6pck/s (25K) Out=138pck/s (333K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2970 RegionTime1=32t/s (263) RegionTime2=31t/s (209) RegionTime3=11t/s (1557) RegionTime4=61t/s (508) CPU=15.5% DOL=10.1% pg/s=0.0 dsk/s=17.1
2017-07-07 13:50:58,149 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 423ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0007E4B7 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:50:58,516 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 360ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0007E4B7 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:51:18,479 - [10] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (10MB) In=6pck/s (25K) Out=98pck/s (336K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2964 RegionTime1=35t/s (264) RegionTime2=29t/s (209) RegionTime3=0t/s (1557) RegionTime4=63t/s (507) CPU=16.2% DOL=9.2% pg/s=0.0 dsk/s=8.8
2017-07-07 13:51:26,802 - [23] - INFO - DOL.GS.WeatherManager - Weather Stopped in Region Ruins of Atlantis (ID 70) CurrentPosition : 652978
[RegionWeather Region=70, Position=524288, Width=63144, Speed=534, Intensity=89, Diffusion=22575, DurationTime=240s, MinPosition=524288, MaxPosition=652968]
2017-07-07 13:51:31,167 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 350ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime4' actionSource: (DOL.GS.Scripts.RewardMob name=cliff dweller spearman DB_ID=d1a9e77e-08c2-4b61-972f-b38bc2908d17 oid=-1 state=Deleted reg=200 loc=231443,443896,1570)
2017-07-07 13:51:48,515 - [23] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (10MB) In=6pck/s (25K) Out=82pck/s (339K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2959 RegionTime1=38t/s (267) RegionTime2=30t/s (211) RegionTime3=1t/s (1557) RegionTime4=58t/s (517) CPU=15.3% DOL=8.7% pg/s=0.0 dsk/s=3.8
2017-07-07 13:51:54,728 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 324ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime4' actionSource: (DOL.GS.Scripts.RewardMob name=cliff dweller DB_ID=5157cfa3-ce6a-458e-ac06-ff5600a8c447 oid=-1 state=Deleted reg=200 loc=231449,443885,1569)
2017-07-07 13:51:56,135 - [RegionTime1] - INFO - DOL.GS.GamePlayer - Bonie saved!
2017-07-07 13:52:18,546 - [23] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=679MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (10MB) In=8pck/s (26K) Out=96pck/s (342K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2960 RegionTime1=31t/s (261) RegionTime2=30t/s (213) RegionTime3=0t/s (1557) RegionTime4=82t/s (948) CPU=15.2% DOL=9.7% pg/s=0.0 dsk/s=5.4
2017-07-07 13:52:24,090 - [37] - INFO - DOL.GS.WeatherManager - Weather Started in Region Avalon (ID 51)
[RegionWeather Region=51, Position=229376, Width=74932, Speed=385, Intensity=34, Diffusion=21176, DurationTime=1045s, MinPosition=229376, MaxPosition=631988]
2017-07-07 13:52:38,411 - [17] - INFO - DOL.GS.GameServer - Saving database...
2017-07-07 13:52:38,412 - [17] - DEBUG - DOL.GS.GameServer - Save ThreadId=17
2017-07-07 13:52:38,829 - [17] - INFO - DOL.GS.GamePlayer - Shroomed saved!
2017-07-07 13:52:38,929 - [17] - INFO - DOL.GS.GamePlayer - Relooc saved!
2017-07-07 13:52:39,185 - [17] - INFO - DOL.GS.GamePlayer - Bitsy saved!
2017-07-07 13:52:39,263 - [17] - INFO - DOL.GS.GamePlayer - Creamofmushroom saved!
2017-07-07 13:52:39,352 - [17] - INFO - DOL.GS.GamePlayer - Bonie saved!
2017-07-07 13:52:39,353 - [17] - DEBUG - DOL.GS.GuildMgr - Saving all guilds...
2017-07-07 13:52:39,354 - [17] - DEBUG - DOL.GS.BoatMgr - Saving all boats...
2017-07-07 13:52:39,385 - [17] - INFO - DOL.GS.GameServer - Saving database complete!
2017-07-07 13:52:39,386 - [17] - INFO - DOL.GS.GameServer - Saved all databases and 5 players in 968ms
2017-07-07 13:52:48,571 - [10] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=1kb/s (10MB) In=6pck/s (26K) Out=45pck/s (343K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2955 RegionTime1=35t/s (267) RegionTime2=29t/s (210) RegionTime3=1t/s (1557) RegionTime4=93t/s (546) CPU=15.3% DOL=8.0% pg/s=0.1 dsk/s=9.5
2017-07-07 13:52:53,813 - [17] - INFO - DOL.GS.WeatherManager - Weather Started in Region Cathal Valley (ID 165)
[RegionWeather Region=165, Position=524288, Width=40878, Speed=502, Intensity=107, Diffusion=19237, DurationTime=211s, MinPosition=524288, MaxPosition=630702]
2017-07-07 13:53:04,326 - [9] - INFO - DOL.GS.GameEvents.ServerListUpdate - Your server's entry was successfully updated!
2017-07-07 13:53:18,600 - [10] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=1kb/s (10MB) In=5pck/s (26K) Out=51pck/s (344K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2957 RegionTime1=33t/s (261) RegionTime2=29t/s (209) RegionTime3=1t/s (1557) RegionTime4=50t/s (563) CPU=16.0% DOL=6.8% pg/s=1.0 dsk/s=3.3
2017-07-07 13:53:48,640 - [10] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (10MB) In=8pck/s (26K) Out=75pck/s (347K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2957 RegionTime1=35t/s (272) RegionTime2=29t/s (212) RegionTime3=0t/s (1557) RegionTime4=53t/s (706) CPU=16.0% DOL=7.4% pg/s=0.3 dsk/s=3.5
2017-07-07 13:54:18,664 - [21] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (10MB) In=9pck/s (27K) Out=73pck/s (349K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2965 RegionTime1=36t/s (264) RegionTime2=29t/s (210) RegionTime3=0t/s (1557) RegionTime4=81t/s (589) CPU=17.4% DOL=7.6% pg/s=11.1 dsk/s=3.9
2017-07-07 13:54:23,232 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 356ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B0630 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:29,546 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 339ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B1EED interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:32,236 - [RegionTime4] - INFO - DOL.GS.GamePlayer - Creamofmushroom saved!
2017-07-07 13:54:32,388 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 533ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B2946 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:35,717 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 332ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B370D interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:40,467 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 584ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B48A2 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:40,802 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:54:40,819 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:54:40,828 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:54:48,678 - [17] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=3kb/s (10MB) In=10pck/s (27K) Out=125pck/s (353K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2969 RegionTime1=34t/s (264) RegionTime2=29t/s (209) RegionTime3=1t/s (1557) RegionTime4=65t/s (555) CPU=17.2% DOL=10.1% pg/s=0.0 dsk/s=18.6
2017-07-07 13:54:56,255 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 314ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000B874C interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:54:58,717 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:54:58,736 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:54:58,758 - [RegionTime4] - WARN - DOL.GS.Ability - Parsing ability display name: keyname='Shield' m_serializedNames='1|Shield Ability: Small Shields;2|Shield Ability: Medium Shields;3|Shield Ability: Large Shields', No Value for Level 0
2017-07-07 13:55:05,277 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 426ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000BAA2E interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:05,567 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 272ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000BABD9 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:08,139 - [21] - INFO - DOL.GS.WeatherManager - Weather Started in Region Molvik (ID 241)
[RegionWeather Region=241, Position=524288, Width=66108, Speed=124, Intensity=107, Diffusion=22523, DurationTime=1061s, MinPosition=524288, MaxPosition=655932]
2017-07-07 13:55:08,898 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 364ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000BB87C interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:09,220 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 317ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000BB87C interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:10,043 - [17] - INFO - DOL.GS.WeatherManager - Weather Stopped in Region Albion Housing (ID 2) CurrentPosition : 648982
[RegionWeather Region=2, Position=393216, Width=59148, Speed=640, Intensity=89, Diffusion=27111, DurationTime=399s, MinPosition=393216, MaxPosition=648972]
2017-07-07 13:55:11,577 - [21] - INFO - DOL.GS.WeatherManager - Weather Started in Region Leirvik (ID 242)
[RegionWeather Region=242, Position=262144, Width=55504, Speed=631, Intensity=103, Diffusion=25149, DurationTime=607s, MinPosition=262144, MaxPosition=645328]
2017-07-07 13:55:18,711 - [21] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=4kb/s (10MB) In=9pck/s (27K) Out=164pck/s (358K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2968 RegionTime1=38t/s (274) RegionTime2=29t/s (208) RegionTime3=0t/s (1557) RegionTime4=101t/s (533) CPU=16.8% DOL=13.0% pg/s=0.0 dsk/s=12.1
2017-07-07 13:55:23,026 - [21] - INFO - DOL.GS.WeatherManager - Weather Stopped in Region Midgard (ID 100) CurrentPosition : 974359
[RegionWeather Region=100, Position=589824, Width=89606, Speed=525, Intensity=46, Diffusion=28058, DurationTime=732s, MinPosition=589824, MaxPosition=974342]
2017-07-07 13:55:23,378 - [37] - INFO - DOL.GS.WeatherManager - Weather Started in Region Ruinrar Atlantis (ID 71)
[RegionWeather Region=71, Position=524288, Width=57078, Speed=581, Intensity=66, Diffusion=30688, DurationTime=211s, MinPosition=524288, MaxPosition=646902]
2017-07-07 13:55:24,424 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 335ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000BF557 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Protector, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Protector, ID=11158, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:31,641 - [39] - DEBUG - DOL.GS.ScriptMgr - Found spell handler DOL.GS.Spells.PrescienceNodeSpellHandler
2017-07-07 13:55:31,658 - [39] - DEBUG - DOL.GS.ScriptMgr - Found spell handler DOL.GS.Spells.PrescienceSpellHandler
2017-07-07 13:55:34,202 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 312ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C1BA0 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Protector, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Protector, ID=11158, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:39,544 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 383ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C3037 interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:42,791 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 300ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C3D2E interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:43,671 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 411ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C403B interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:44,015 - [RegionTime4] - INFO - DOL.GS.GamePlayer - Creamofmushroom saved!
2017-07-07 13:55:44,117 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 440ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C40DF interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Protector, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Protector, ID=11158, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:47,541 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 444ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C4F2B interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:47,937 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 393ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C4F2B interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:48,285 - [RegionTime4] - WARN - DOL.GS.GameTimer+TimeManager - callback took 319ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x000C504D interval:100 manager:'RegionTime4' spellhandler: (Caster=Forest's Incarnation, IsCasting=True, m_interrupted=False
Spell: Name=Forest's Incarnation, ID=11332, SpellType=DirectDamage
SpellLine: KeyName=Mob Spells, ID=0, Icon=0, Level=1)
2017-07-07 13:55:48,743 - [10] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=5kb/s (10MB) In=8pck/s (27K) Out=183pck/s (363K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2968 RegionTime1=36t/s (269) RegionTime2=29t/s (209) RegionTime3=1t/s (1557) Region
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby psychoninja911 » Fri Jul 07, 2017 7:30 pm

Nvm... They're back to drawing high usage.\

Image

Ignore that shield thing, I'll fix that soon :P


#Edit: Do you think that the hard drive is just weak or slow?
I don't know what the deal could be... I have 14 people online right now, and it's at ease. HD staying under 20% usage. I can't imagine all 14 people are just sitting afk in town.

#Edit 2: Spoke too soon. Pegged out again:

Image
psychoninja911
DOL Novice
 
Posts: 51
Joined: Fri Oct 07, 2011 6:46 pm
Top

Re: Long callback times and high Hard Drive usage.

Postby Loki » Fri Jul 07, 2017 9:59 pm

try this
Attachments
Rpbpmob.cs
(4.09 KiB) Downloaded 17 times
“ If debugging is the process of removing software bugs, then programming must be the process of putting them in. ”

Join https://discord.gg/r3T2U7S Official DOL Discord Chat
User avatar
Loki
Developer
 
Posts: 468
Joined: Fri Jun 23, 2006 2:14 am
Location: uk
Top
Post a reply

Return to “%s” Support

Who is online

Users browsing this forum: No registered users and 0 guests

Website by Etaew - © 2012 Dawn of Light - Powered by phpBB © 2011 phpBB Group

Artwork and screen shots Copyright © 2001-2004 Mythic Entertainment, Inc. All rights reserved. Used with permission of Mythic Entertainment. Mythic Entertainment, the Mythic Entertainment logo, "Dark Age of Camelot," "Shrouded Isles," "Foundations," "New Frontiers," "Trials of Atlantis," "Catacombs," "Darkness Rising," the Dark Age of Camelot and subsequent logos, and the stylized Celtic knot are trademarks of Mythic Entertainment, Inc.