Long #repop and zone times after source upgrade

[Bohbo]

I am banging my head on this one. I noticed an error in the log about being unable to load plugins.pl not sure if that has anythng to do with it because my plugins all seems to work. #repop used to start working right away. After updating to newest source i started getting these problems.

[epilz]

I updated to the latest source this morning, and have not seen any errors so far on my test server. #repop is instantly on my box

[trevius]

Plugins.pl has nothing to do with your problem.

What version/Date of source were you previously running and which one are you using now? Are you seeing a lot of chat messages in game? If you have a lot of logging turned on, I have found that it tends to start queuing up and #commands aren't very responsive or won't work at all if you are getting spammed too much.

I haven't seen any issues with #repop or zoning other than when I had turned on perl debug logging and was using a massive loop that was sending non-stop quest::debug() messages to my chat window.

[Bohbo]

I tried recompiling to latest source just now. Still have repop issue.

I just setup a fresh server too and migrated my custom DB info to it and effectively did a playerdata content wipe.

In CB for example i have 200 mobs about, it takes ~20 seconds to repop and during that time players will disapear briefly, MQ2EQBC drop its connection, and / commands dont work.

I don't however get a lot or any abnormal amounts of debug info in the chat box, just occasional stuff.

@Trev Where would I go to check debug logging? As far as source goes, my last updated was about a week ago. I usually try and update it once a week.

[dagulus2]

Originally Posted by trevius

If you have a lot of logging turned on, I have found that it tends to start queuing up and #commands aren't very responsive or won't work at all if you are getting spammed too much.

Ah, that explains a lot! Thanks.

[Bohbo]

Originally Posted by epilz

I updated to the latest source this morning, and have not seen any errors so far on my test server. #repop is instantly on my box

I tried renaming the logs, quests, and plugins folder to try and sniff out the problem. None of which had any significant effect on the problem.

14 Sec loading without logs, quests or plugin folder.
23 Sec Loading with log folder, quests, and plugin.

In my log folder under subdir called 'zone' i am getting big log files like 10mb to 30mb for a specific zone.

What is weird though is when the quest is commented away that loads the weapon plugin random weapons on spawn it still has a substantial lag in popping. (The script used to work with instant popping without issues)/

[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:32] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 11:58:33] [Quests] Argument "SHIELD" isn't numeric in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:33] [Quests] Argument "1HB" isn't numeric in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:33] [Quests] Argument "1HP" isn't numeric in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:33] [Quests] Argument "1HS" isn't numeric in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 11:58:33] [Zone Server] Time Broadcast Packet: EQTime [03:10 am]

[Bohbo]

One last thing... I looked at my log.ini file in my server directory and everything there is set to = off

So no idea why logs are even trying to be generated.

On my client logs i get this as the only event that logs during a repop

[Fri Feb 06 12:12:52 2015]00408:Invalid render material type.

[Fri Feb 06 12:12:52 2015]00409:CRender::CreateBatchWithoutColor() failed.

[Akkadius]

If you read the changelog.txt you'd know that the logging system got an absolute overhaul:

http://wiki.eqemulator.org/p?Logging_System_Overhaul

[Bohbo]

Originally Posted by Akkadius

If you read the changelog.txt you'd know that the logging system got an absolute overhaul:

http://wiki.eqemulator.org/p?Logging_System_Overhaul

Thanks for pointing that out.

So using CB as an example with ALL logging off repop is now about 12-14 sec. So i shaved off that extra bit i was getting with logging.

Another zone with signficantly less NPC ~30 repops in about 4 sec down from ~7.

This is definitely an improvement but nowhere as close to the instant repopping I enjoyed before. Any additional holes you can think of i can run down?

[Akkadius]

Make sure this is unchecked in your CMAKE:

[Bohbo]

Originally Posted by Akkadius

Make sure this is unchecked in your CMAKE:

I recompiled after making the change in CMAKE and that didn't really seem to make a measurable difference. Repop times aren't terrible but on a zone with only 30 mobs it seems to me I remember repopping being faster than 4 sec / 12 sec per zone respectively.

[Akkadius]

Originally Posted by Bohbo

I recompiled after making the change in CMAKE and that didn't really seem to make a measurable difference. Repop times aren't terrible but on a zone with only 30 mobs it seems to me I remember repopping being faster than 4 sec / 12 sec per zone respectively.

Well there are varying factors but my repops are instant.

[Bohbo]

Originally Posted by Akkadius

Well there are varying factors but my repops are instant.

Hmmm, the server machine should be well beyond the specs needed.
W8.1 x64 with 8gb of RAM and dual core 3.2ghz. I will have to setup a new test server and experiment more. If you think of anything please dont hesitate to drop by and leave a suggestion. I do miss instant repop.

[Akkadius]

Originally Posted by Bohbo

Hmmm, the server machine should be well beyond the specs needed.
W8.1 x64 with 8gb of RAM and dual core 3.2ghz. I will have to setup a new test server and experiment more. If you think of anything please dont hesitate to drop by and leave a suggestion. I do miss instant repop.

Paste your log table settings

[Bohbo]

Originally Posted by Akkadius

Paste your log table settings

Right now everything is set to 0.

I noticed the quest logging was taking up a huge amount of time.

Throwing up errors for this code in global_NPC.pl

sub EVENT_SPAWN{
	if ($mname =~/#/){
	}
	else{
	plugin::RandomWeapons(1, 11169, 100, 200, 230);
	}
}

Keep in mind before this would add maybe 1 sec to repop times. Purpose is to give non named NPC random weapons. The quest logging under zone is making huge 30mb to 100mb files

2-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $PrimType in string eq at plugins/weapon_tools.pl line 944.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in array element at plugins/weapon_tools.pl line 948.
[02-06-2015 :: 13:23:37] [Quests] Use of uninitialized value $SecType in string eq at plugins/weapon_tools.pl line 957.

It also spits out in blocks of PrimTYpe and Sec type not just alternating. With the quest code disabled that is where i get the 10 sec drop (writing to HD). Not sure how i can fix that error with weapons other than not logging (not ideal). But there still seems to be a missing chunk of time that scales based on # of NPCs in zone. 30 NPC = 4 sec 200 = 14sec.