Long callback times and high Hard Drive usage.

[psychoninja911]

Hey guys, here's an error code I'm receiving below.

I do NOT get this on my admin account, which is connecting through WAN IP/Portal list. Not by local IP or loopback.
To fill you in, what you're looking at here is a high dmg shield group buff on a short duration. Basically a group focus pull (with no interruption or the focus part).
But we're also getting errors on his melee style kills too. This happens when mobs die.

This is taking a TON of Hard Drive resources. I can't get my hard drive to spike like this from my account. I can seriously spam a /cast spell macro and aoe a bunch of mobs, instantly killing them, and I won't get a single error or any lag. Hard drive will remain idle.

But when this guys killin' stuff... It goes nuts.

I researched errors, and all I found was stuff in reference to salvage being turned on or off, but no one else is online salvaging.
Super thanks everyone

2017-07-04 00:23:14,885 - [9] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (12MB) In=6pck/s (25K) Out=76pck/s (430K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2932 RegionTime1=49t/s (785) RegionTime2=23t/s (163) RegionTime3=1t/s (1557) RegionTime4=0t/s (163) CPU=19.1% DOL=4.8% pg/s=0.0 dsk/s=2.2
2017-07-04 00:23:33,802 - [29] - INFO - DOL.GS.WeatherManager - Weather Started in Region Molvik (ID 241)
[RegionWeather Region=241, Position=524288, Width=76590, Speed=218, Intensity=30, Diffusion=19519, DurationTime=651s, MinPosition=524288, MaxPosition=666414]
2017-07-04 00:23:44,910 - [9] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=680MB Clients=5 Down=0kb/s (0MB) Up=3kb/s (12MB) In=6pck/s (25K) Out=99pck/s (433K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2935 RegionTime1=50t/s (812) RegionTime2=24t/s (163) RegionTime3=1t/s (1557) RegionTime4=0t/s (163) CPU=18.9% DOL=5.7% pg/s=0.3 dsk/s=4.0
2017-07-04 00:23:58,108 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 610ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Reflect Offense, ID=574, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=9
2017-07-04 00:23:58,111 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 618ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=forest smuggler DB_ID=074c44ea-4dbb-4cba-8eea-fff63765bd62 oid=-1 state=Deleted reg=1 loc=533049,410277,5551)
2017-07-04 00:24:09,717 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 609ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Reflect Offense, ID=574, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=9
2017-07-04 00:24:09,722 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 615ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=forest smuggler DB_ID=bf5aefa9-f7f1-42b2-9b2c-dbd22cb896b9 oid=-1 state=Deleted reg=1 loc=532991,410293,5543)
2017-07-04 00:24:14,943 - [9] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=4kb/s (13MB) In=8pck/s (25K) Out=142pck/s (437K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2935 RegionTime1=65t/s (803) RegionTime2=23t/s (163) RegionTime3=0t/s (1557) RegionTime4=0t/s (163) CPU=19.7% DOL=7.8% pg/s=0.0 dsk/s=7.4
2017-07-04 00:24:15,562 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 578ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Reflect Offense, ID=574, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=9
2017-07-04 00:24:15,571 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 592ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=forest smuggler DB_ID=245bfd24-0ace-40a3-8dec-29166bbdb317 oid=-1 state=Deleted reg=1 loc=532931,410304,5533)
2017-07-04 00:24:16,152 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 576ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=17345 state=Active reg=1 loc=532828,410324,5516 class=Mercenary(11))
2017-07-04 00:24:18,202 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 672ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Reflect Offense, ID=574, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=9
2017-07-04 00:24:18,205 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 684ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=forest smuggler DB_ID=d6a2ff81-ba84-48ab-bdc0-f5f39b7bb633 oid=-1 state=Deleted reg=1 loc=532947,410301,5536)
2017-07-04 00:24:35,194 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 640ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Reflect Offense, ID=574, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=9
2017-07-04 00:24:44,972 - [29] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=681MB Clients=5 Down=0kb/s (0MB) Up=2kb/s (13MB) In=4pck/s (25K) Out=81pck/s (439K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2935 RegionTime1=29t/s (794) RegionTime2=23t/s (163) RegionTime3=0t/s (1557) RegionTime4=0t/s (163) CPU=19.4% DOL=5.9% pg/s=0.0 dsk/s=18.7
2017-07-04 00:24:53,897 - [16] - ERROR - DOL.GS.GameEvents.RegionTimersResynch - ----- Found Frozen Region Timer -----
Name: RegionTime1 - Current Time: 4151932
2017-07-04 00:25:04,213 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 29652ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=Captain Taff DB_ID=e88c7374-6c62-494a-86ad-3c62f1183914 oid=-1 state=Deleted reg=1 loc=534075,410049,5633)
2017-07-04 00:25:04,213 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - RegionTime1 out of sync, over 2000ms lost! -29651
2017-07-04 00:25:04,214 - [RegionTime1] - INFO - DOL.GS.GameTimer+TimeManager - stopped timer thread RegionTime1 (ID:43)

[psychoninja911]

To add to this, I created a merc as well (on a normal guest account), using the same styles, and I'm not getting the errors.

Same thing with a cabalist. On a guest account (or NON admin account), I logged in through WAN, created cabalist, body tanked some mobs to death with focus/dmg shield.
No errors.

[Loki]

Out of curiosity! Do you get this on other mobs apart from DOL.GS.Scripts.RewardMob ?

[psychoninja911]

Yeah I believe I did. I think we tested it on some random bandit mobs nearby.

[Loki]

Have a look @ this http://www.dolserver.net/viewtopic.php? ... mass+death

It might give you some insight into the problem.

[PlanarChaosRvrtwo]

jaystar that is totally diffrent issue the topic you mean have warn logs and issues couse of massive mob count kill.
Thus tipic here have to do with old scripts that work wrong in core,
Basicly you can say the weak multicast delegate is something like proper skill but solved wrong code wise (in some case its not even wrong but just not supported)

For example you do aedot and then instantly do aedisease it work correct but code wise its handled in 5 steps not in 3.

[psychoninja911]

I can definitely see how that will stack up response times...
However, it doesn't do it to me... Only a couple of players now, (and not always while using the styles/spells).

It does sort of look like it's the RewardMob's death that's causing lag.
That mixed with maybe their location or internet speed? Just a guess. I don't get the error messages, even if I VPN jump to the server.

[Loki]

jaystar that is totally diffrent issue the topic you mean have warn logs and issues couse of massive mob count kill.
Thus tipic here have to do with old scripts that work wrong in core,
Basicly you can say the weak multicast delegate is something like proper skill but solved wrong code wise (in some case its not even wrong but just not supported)

For example you do aedot and then instantly do aedisease it work correct but code wise its handled in 5 steps not in 3.

Why I asked if it happened on others apart from the DOL.GS.Scripts.RewardMob, which the OP said he believed it did.

I suggested he could take something from there or maybe grab some ideas, NOT that it was the same issue.

Is the server and MySQL on the same machine? Try without the RewardMob or post your script and hopefully somebody may see a problem with it for you.

[PlanarChaosRvrtwo]

Ah thanks for xplain i understand now^^ your right i just thougt to slow it seemt^^
But if its Reward mob and only some get that error i guess its an problem with packages couse possible that script use something from client that now use diffrent package.libs.
Just as an idea

Ah it happen on mobs death? i guess the way how reward is added to player is based upon client version the players use like for example:
it were added with version 1.109 and player use 1.109 and everything is fine
but an uthgard player come with an 1.65dll and the client dont have some libs so the communication of server and client is ''not clean''

and i guess the reward isnt handled as drops the death is just triggering and add bps to player script?

[psychoninja911]

Here's my RewardMob script.
I got it from someone on here

I need to do more testing when no one is online but the original guy causing it, just so I can slowly see if it's ONLY these mobs, or if it's all mobs.

I don't believe it's from too many of them dying alone. That could contribute to it. I know he can kill 1 mob and he starts getting these errors. Other people are giving the error too now.

I DID modify this script before compiling it... I reduced the damage down to * 1, opposed to the *4 that it normally sits at.
I don't know if this has an effect on the mob dying or not. But I planned on upping it to *2 eventually anyway.

Code: Select all

///////////////////////////////////////////////////
//////// BPMobds 1.0 ////////
//////// Copyright 2009 by Amuny ////////
///////////////////////////////////////////////////
// Edited by Spellfire :
// Change: The name of mob from BPMob to RewardMob to remove any confusion.
// Addition: Mob level ranges + suitable rps for their level. Line 60 of this script.
// Addition: Random Jackpot and Bonus RP system from an old script author unkown. Line 50 of this script.
// Addition: Mobs will now ignore the modifier set in server properties table. Line 32 of this script.
// Question: Bonus RP system not giving correct values?. Line 89 of this script.
// Question: How do I change the group rp range from region to an actual range value?. Line 130 of this script.
// Question: ?I may have something mixed up or inserted in the wrong area but the mobs are giving exp twice?.
using DOL.GS.ServerProperties;
using System.IO;
using System.Reflection;
using System.Collections.Generic;
using DOL.AI;
using DOL.Language;
using DOL.GS.Effects;
using DOL.GS.Movement;
using DOL.GS.Quests;
using DOL.GS.Spells;
using DOL.GS.Utils;
using DOL.GS.Housing;
using DOL.GS.RealmAbilities;
using System;
using System.Collections;
using DOL.Database;
using DOL.Events;
using DOL.GS;
using DOL.GS.PacketHandler;
using DOL.GS.Scripts;
using System.Threading;
using log4net;
using DOL.AI.Brain;

namespace DOL.GS.Scripts
{
public class RewardMob : GameNPC
{

public override bool AddToWorld()
{
//this makes the mob non-peaceful upon creation.
Flags = 0;



return base.AddToWorld();
}




public override void Die(GameObject killer)
{
GamePlayer player = killer as GamePlayer;

//amount of rp/bp gain based on the mob level
int rewardrp = 0;
int rewardbp = 0;

if (Level >= 5 && Level <= 9) { rewardbp = 1; rewardrp = 1; }
if (Level >= 10 && Level <= 14) { rewardbp = 2; rewardrp = 2; }
if (Level >= 15 && Level <= 19) { rewardbp = 3; rewardrp = 3; }
if (Level >= 20 && Level <= 24) { rewardbp = 4; rewardrp = 4; }
if (Level >= 25 && Level <= 29) { rewardbp = 5; rewardrp = 10; }
if (Level >= 30 && Level <= 34) { rewardbp = 25; rewardrp = 20; }
if (Level >= 35 && Level <= 39) { rewardbp = 26; rewardrp = 40; }
if (Level >= 40 && Level <= 44) { rewardbp = 27; rewardrp = 80; }
if (Level >= 45 && Level <= 49) { rewardbp = 28; rewardrp = 600; }
if (Level >= 50 && Level <= 54) { rewardbp = 29; rewardrp = 900; }
if (Level >= 55 && Level <= 59) { rewardbp = 30; rewardrp = 1000; }
if (Level >= 60 && Level <= 64) { rewardbp = 60; rewardrp = 2000; }
if (Level >= 65 && Level <= 69) { rewardbp = 90; rewardrp = 27000; }
if (Level >= 70 && Level <= 74) { rewardbp = 120; rewardrp = 5120; }
if (Level >= 75 && Level <= 79) { rewardbp = 150; rewardrp = 10240; }
if (Level >= 80 && Level <= 84) { rewardbp = 180; rewardrp = 20480; }
if (Level >= 85 && Level <= 89) { rewardbp = 210; rewardrp = 40960; }
if (Level >= 90 && Level <= 94) { rewardbp = 5000; rewardrp = 81920; }
if (Level >= 95 && Level <= 99) { rewardbp = 5000; rewardrp = 163840; }
if (Level >= 100 && Level <= 255) { rewardbp = 100000; rewardrp = 327680; }



bool jackpot;
// Jackpot Mulitplier set to *2 and *3 rps.
int multiplier = Util.Random(2, 3);
// Bonus can be used for holidays and other events. Set at +1 to +5 rps.
//int bonus = Util.Random(1, 2); // bonus not working correctly
// Chance of getting a Jackpot , set to 1 in 2.
int chance = Util.Random(1, 2);


if (chance == 2)
{
jackpot = true;
}
else
{
jackpot = false;
}
if (jackpot)
{
rewardrp = ((rewardrp) * multiplier);
// rewardrp = ((rewardrp + bonus) * multiplier);// bonus not working correctly
}
else
{
rewardrp = (rewardrp);
// rewardrp = (rewardrp + bonus);// bonus not working correctly
}



//If they worth the reward of the killed mob
if (player is GamePlayer && IsWorthReward)
{
//If the player have a group, then we split the reward amount
if (player.Group != null)
{
if (player.Group.MemberCount == 1) { } //We don't affect the reward if there is only one player
if (player.Group.MemberCount == 2) { rewardbp = (rewardbp / 2); rewardrp = (rewardrp / 2); }
if (player.Group.MemberCount == 3) { rewardbp = (rewardbp / 3); rewardrp = (rewardrp / 3); }
if (player.Group.MemberCount == 4) { rewardbp = (rewardbp / 4); rewardrp = (rewardrp / 4); }
if (player.Group.MemberCount == 5) { rewardbp = (rewardbp / 5); rewardrp = (rewardrp / 5); }
if (player.Group.MemberCount == 6) { rewardbp = (rewardbp / 6); rewardrp = (rewardrp / 6); }
if (player.Group.MemberCount == 7) { rewardbp = (rewardbp / 7); rewardrp = (rewardrp / 7); }
if (player.Group.MemberCount >= 8) { rewardbp = (rewardbp / 8); rewardrp = (rewardrp / 8); }

//We give the reward to all groupmembers in the same region of the killer
foreach (GamePlayer player2 in player.Group.GetMembersInTheGroup())
{
if (player2.CurrentRegionID == player.CurrentRegionID)
{

player2.GainRealmPoints(rewardrp, false);// false will disable the modifier in server properties table for this mob.
player2.GainBountyPoints(rewardbp, false);// false will disable the modifier in server properties table for this mob.

}
}
}
//If the player is not in a group, we give him the reward
else
{

player.GainRealmPoints(rewardrp, false); // false will disable the modifier in server properties table for this mob.
player.GainBountyPoints(rewardbp, false);// false will disable the modifier in server properties table for this mob.

}
}

//The mob is killed by the killer
base.Die(killer);

//Begin the mob's respawn timer
StartRespawn();
}

private static readonly ILog log = LogManager.GetLogger(System.Reflection.MethodBase.GetCurrentMethod().DeclaringType);

GameLiving m_DopplegangerTarget;
public GameLiving DopplegangerTarget
{
get
{
return m_DopplegangerTarget;
}
set
{
m_DopplegangerTarget = value;
}
}

public override int MaxHealth
{
get
{
return base.MaxHealth * 2;
}
}

/* public override int MaxSpeedBase
{
get
{
return 191 ;
}
set
{
m_maxSpeedBase = value;
}
}
*/

public override double AttackDamage(InventoryItem weapon)
{
return base.AttackDamage(weapon) * 1;
}

public override void StartAttack(GameObject attackTarget)
{


base.StartAttack(attackTarget);
}
public void SwitchToMelee(GameObject target)
{
SwitchWeapon(eActiveWeaponSlot.Standard);
this.Health = this.MaxHealth;
}
}
}





Here's more from someone else though...

Code: Select all

2017-07-05 01:51:58,151 - [34] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=688MB Clients=4 Down=0kb/s (14MB) Up=1kb/s (203MB) In=5pck/s (408K) Out=46pck/s (6753K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=65t/s (420) RegionTime2=50t/s (191) RegionTime3=7t/s (1557) RegionTime4=0t/s (183) CPU=10.1% DOL=7.5% pg/s=0.0 dsk/s=0.7
2017-07-05 01:52:24,719 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 657ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Zarmo, IsCasting=False, m_interrupted=False
Spell: Name=Channel Blow, ID=576, SpellType=DamageShield
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27
2017-07-05 01:52:24,722 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 650ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=slime lizard DB_ID=057f2310-4897-4a4d-a158-fd1be61e3ce6 oid=-1 state=Deleted reg=1 loc=471824,635080,1923)
2017-07-05 01:52:25,056 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 321ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=slime lizard DB_ID=66cc712a-31b0-4b3c-96c2-5a6d05bbcb9c oid=-1 state=Deleted reg=1 loc=471833,635078,1922)
2017-07-05 01:52:28,166 - [47] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=686MB Clients=4 Down=0kb/s (14MB) Up=3kb/s (204MB) In=5pck/s (408K) Out=99pck/s (6756K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=108t/s (497) RegionTime2=49t/s (191) RegionTime3=4t/s (1557) RegionTime4=0t/s (183) CPU=11.1% DOL=11.6% pg/s=0.0 dsk/s=3.1
2017-07-05 01:52:30,569 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 446ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=slime lizard DB_ID=3f6e0679-83b2-436b-b7b3-9320a6fc5577 oid=-1 state=Deleted reg=1 loc=471926,635346,1978)
2017-07-05 01:52:58,195 - [35] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=686MB Clients=4 Down=0kb/s (14MB) Up=2kb/s (204MB) In=9pck/s (409K) Out=90pck/s (6759K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=169t/s (492) RegionTime2=51t/s (191) RegionTime3=0t/s (1557) RegionTime4=0t/s (183) CPU=11.6% DOL=7.8% pg/s=0.0 dsk/s=3.6
2017-07-05 01:53:13,645 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 799ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x0008023E interval:100 manager:'RegionTime1' spellhandler: (Caster=Zarmo, IsCasting=True, m_interrupted=False
Spell: Name=Perforate Spirit, ID=582, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27)
2017-07-05 01:53:16,671 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 450ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=slime lizard DB_ID=e38c353b-5965-4777-81c5-0fff048475c3 oid=-1 state=Deleted reg=1 loc=473884,636429,2026)
2017-07-05 01:53:26,061 - [36] - INFO - DOL.GS.WeatherManager - Weather Stopped in Region Albion Housing (ID 2) CurrentPosition : 625683
[RegionWeather Region=2, Position=393216, Width=35848, Speed=603, Intensity=86, Diffusion=28224, DurationTime=385s, MinPosition=393216, MaxPosition=625672]
2017-07-05 01:53:28,226 - [36] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=687MB Clients=4 Down=0kb/s (14MB) Up=1kb/s (204MB) In=7pck/s (409K) Out=60pck/s (6761K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=89t/s (468) RegionTime2=51t/s (191) RegionTime3=0t/s (1557) RegionTime4=0t/s (183) CPU=11.9% DOL=6.5% pg/s=0.7 dsk/s=12.6
2017-07-05 01:53:58,244 - [8] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=687MB Clients=4 Down=0kb/s (14MB) Up=1kb/s (204MB) In=6pck/s (409K) Out=52pck/s (6762K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=75t/s (473) RegionTime2=51t/s (191) RegionTime3=1t/s (1557) RegionTime4=0t/s (183) CPU=9.9% DOL=7.3% pg/s=0.0 dsk/s=3.0
2017-07-05 01:54:28,274 - [43] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=687MB Clients=4 Down=0kb/s (14MB) Up=2kb/s (204MB) In=6pck/s (409K) Out=92pck/s (6765K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2995 RegionTime1=100t/s (495) RegionTime2=49t/s (191) RegionTime3=0t/s (1557) RegionTime4=0t/s (183) CPU=10.8% DOL=8.3% pg/s=0.0 dsk/s=0.5
2017-07-05 01:54:29,483 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 379ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=b1351306-1aca-41e8-b33c-ffaaf2e5ff94 oid=-1 state=Deleted reg=1 loc=470898,634607,1929)
2017-07-05 01:54:31,536 - [RegionTime1] - WARN - DOL.WeakMulticastDelegate - InvokeSafe took 562ms! method: DOL.GS.Spells.DamageShieldSpellHandler.EventHandler target: Caster=Varmo, IsCasting=False, m_interrupted=False
Spell: Name=Diabolic Briars, ID=14222, SpellType=DamageShield
SpellLine: KeyName=Heretic Enhancement Spec, ID=0, Icon=0, Level=23
2017-07-05 01:54:31,541 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 569ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=47d6604f-a512-4878-b7e9-329195f1e22b oid=-1 state=Deleted reg=1 loc=470899,634607,1927)
2017-07-05 01:54:32,966 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 456ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x00093979 interval:100 manager:'RegionTime1' spellhandler: (Caster=Zarmo, IsCasting=True, m_interrupted=False
Spell: Name=Perforate Spirit, ID=582, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27)
2017-07-05 01:54:33,440 - [RegionTime1] - 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-05 01:54:33,710 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 510ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=6210690f-d982-4361-b949-52b9cb3e782f oid=-1 state=Deleted reg=1 loc=470900,634608,1929)
2017-07-05 01:54:34,588 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 384ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=dd0208ce-b0e7-4a9f-b3e0-1142b81c5ccd oid=-1 state=Deleted reg=1 loc=470899,634607,1929)
2017-07-05 01:54:36,783 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 382ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=dc1f1629-c93c-4715-ba83-c86f2044bb65 oid=-1 state=Deleted reg=1 loc=470901,634596,1928)
2017-07-05 01:54:41,119 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 374ms! effect: (Duration=12000, Owner.Name=creeping crud, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Zarmo, IsCasting=False, m_interrupted=False
Spell: Name=Perforate Spirit, ID=582, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27)
2017-07-05 01:54:43,095 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 353ms! effect: (Duration=12000, Owner.Name=creeping crud, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Zarmo, IsCasting=False, m_interrupted=False
Spell: Name=Perforate Spirit, ID=582, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27)
2017-07-05 01:54:43,419 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 320ms! effect: (Duration=12000, Owner.Name=creeping crud, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Zarmo, IsCasting=False, m_interrupted=False
Spell: Name=Perforate Spirit, ID=582, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=27)
2017-07-05 01:54:43,716 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 292ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePet name=Ruby Simulacrum DB_ID= oid=20172 state=Active reg=1 loc=470883,634706,1949)
2017-07-05 01:54:49,046 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 353ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.Scripts.RewardMob name=creeping crud DB_ID=ab83486a-f954-4f69-8894-36fb42ec2089 oid=-1 state=Deleted reg=1 loc=470899,634608,1928)

[Loki]

To follow up on what PlanarChaosRvrtwo mentioned, in your Database what is the Last client version of said player? Account Table.

Are all your players using the same client?

How many mobs are been pulled seeing as this is a focus shield grp.

[psychoninja911]

It seems the majority I'm having issue with are 1109.
Although, I think my server is 1109 as well...

I see other players in here with 1122 and 1104, but I'm not seeing error logs from them yet, at least not that I've found.

[Loki]

All those "if" statements, Maybe if a player matches the level within the if statement then don't go through all the other if statements ?

Maybe set the client version in DB to allow only 1109, are you using the current SVN?

[psychoninja911]

All those "if" statements, Maybe if a player matches the level within the if statement then don't go through all the other if statements ?

Maybe set the client version in DB to allow only 1109, are you using the current SVN?

Yeah I believe I am using the current svn.

Here's him mass pulling at goblins for me. NOT reward mobs.
I got the errors, but it wasn't AS bad as reward mobs.

2017-07-05 19:52:47,596 - [10] - INFO - DOL.GS.PacketHandler.PacketProcessor - PacketPreprocessor: Preprocessor prevents handling of a packet with packet.ID=220
2017-07-05 19:52:47,975 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 431ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329356,684809,2727 class=Mercenary(11))
2017-07-05 19:52:49,988 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 487ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329400,684926,2720 class=Mercenary(11))
2017-07-05 19:52:52,672 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 342ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=3c54dca4-c825-4d7f-9a15-ad744cfdc224 oid=-1 state=Deleted reg=1 loc=329406,684823,2724)
2017-07-05 19:52:53,011 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 322ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=46e0d8b5-b09d-4096-beb7-10bb95fe5b4a oid=-1 state=Deleted reg=1 loc=329465,684850,2720)
2017-07-05 19:52:53,353 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 299ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=0f9d7506-7f19-4b86-a74e-521d4d64f388 oid=-1 state=Deleted reg=1 loc=329409,684824,2724)
2017-07-05 19:52:53,522 - [30] - INFO - DOL.GS.GameEvents.StatPrint - -stats- Mem=682MB Clients=8 Down=0kb/s (3MB) Up=15kb/s (66MB) In=13pck/s (116K) Out=536pck/s (2205K) Pool=1022/1023(4) IOCP=1000/1000(4) GH/OH=42/2981 RegionTime1=252t/s (691) RegionTime2=65t/s (274) RegionTime3=1t/s (1557) RegionTime4=10t/s (165) CPU=19.1% DOL=23.6% pg/s=0.0 dsk/s=8.2
2017-07-05 19:52:53,694 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 324ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=9caee03c-dcd5-4abf-8e12-3ee0dddad101 oid=-1 state=Deleted reg=1 loc=329426,684822,2723)
2017-07-05 19:52:54,354 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 458ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=e565b8cd-8fde-4455-8149-eac7ca259412 oid=-1 state=Deleted reg=1 loc=329417,684810,2724)
2017-07-05 19:52:55,109 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 692ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329400,684926,2720 class=Mercenary(11))
2017-07-05 19:52:57,681 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 1307ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329400,684926,2720 class=Mercenary(11))
2017-07-05 19:53:00,291 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 1363ms! DOL.GS.Spells.SpellHandler+DelayedCastTimer tick:0x001AC865 interval:100 manager:'RegionTime1' spellhandler: (Caster=Carrera, IsCasting=True, m_interrupted=False
Spell: Name=Drill Spirit, ID=584, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=43)
2017-07-05 19:53:01,717 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 1386ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329400,684926,2720 class=Mercenary(11))
2017-07-05 19:53:02,600 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 389ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=f76becc4-ba31-428c-bffd-b40a1dc19796 oid=-1 state=Deleted reg=1 loc=329418,684806,2725)
2017-07-05 19:53:03,288 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 350ms! effect: (Duration=12000, Owner.Name=pygmy goblin, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Drill Spirit, ID=584, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=43)
2017-07-05 19:53:03,636 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 338ms! effect: (Duration=12000, Owner.Name=pygmy goblin, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Drill Spirit, ID=584, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=43)
2017-07-05 19:53:04,715 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 1038ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GamePlayer name=Sagaris DB_ID=6d83fe87-bac7-413f-b379-4ba6d68e8769 oid=2820 state=Active reg=1 loc=329400,684926,2720 class=Mercenary(11))
2017-07-05 19:53:06,329 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 384ms! DOL.GS.GameLiving+WeaponOnTargetAction tick:0x8000000040000000 interval:0 manager:'RegionTime1' actionSource: (DOL.GS.GameNPC name=pygmy goblin DB_ID=78acabdc-39ca-46db-944b-ce6a29ee6d9a oid=-1 state=Deleted reg=1 loc=329404,684814,2724)
2017-07-05 19:53:06,681 - [RegionTime1] - WARN - DOL.GS.GameTimer+TimeManager - callback took 336ms! effect: (Duration=12000, Owner.Name=pygmy goblin, PulseFreq=2000, RemainingTime=0, Effectiveness=1, m_expired=True
SpellHandler info: Caster=Carrera, IsCasting=False, m_interrupted=False
Spell: Name=Drill Spirit, ID=584, SpellType=DamageOverTime
SpellLine: KeyName=Matter Manipulation, ID=0, Icon=0, Level=43)
2017-07-05

[PlanarChaosRvrtwo]

Double tell misstake