Critical: World slowness, zone & client issues, blah.

Old bugs stored here for reference.
User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Critical: World slowness, zone & client issues, blah.

Post by John Adams » Tue Sep 06, 2011 10:10 am

On the subject of newly introduced bugs ;)

Scatman is the only character on EQ2TC, and has been since 9/5/2011 @ 18:30. Looks like another toon had logged out around 18:50, and consequently shut down the Darklight Woods zone. Scat, I believe zoned into The Ruins around 18:56. It took a full 2 minutes for him to zone in, and likely crashed because at 18:58, I see him reconnect... but then, nothing. Ruins shut down.

Right now, over 12 hours later, World shows 2 Clients connected (false) and 1 Zone running (may be true, if Scatman is indeed still connected somewhere, which I doubt).

We have two MAJOR problems right now;
1) Zones are not shutting down due to Clients not fully disconnecting
2) Incredible slowness of processing inside World - with NO sign of excess processing time, CPU, Memory or disk I/O inside a populated zone.

#2 example is doing /who all in QC, and it taking 10-20 seconds to respond. While doing /who all in GMHall is instantaneous.
another example is it taking MINUTES to log into a zone -- the same zones, same content we have run for years. So it is a code change, not a content change, that is causing this grief.


I'd like some solid ideas on what I can do (server-side) to try and trace the issues. Note: Any time I place Logging around the functions, all problems cease to exist... so Logging apparently is not going to help. Maybe there is a clue to the issues in the fact that excessive Logging function calls are slowing things down enough where World doesn't eat itself alive? I dunno, I am out of options.

I'd appreciate some feedback on what to do.


Current Console Session:

Code: Select all

16:56:47 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
16:56:47 D Net: AddAuth: 25 Key: 1315267007
16:56:48 D Net: New client from ip: 68.45.169.197 port: 50483
16:56:48 D Net: Adding new client...
16:56:48 D Client: Client::Process, ProcessQuestUpdates
16:56:49 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
16:56:49 I ZoneAuth: Access Key: 1315267007, Character Name: Scatman, Account ID: 25
16:56:49 D Player: Loading character for 'Scatman' (char_id: 2)
16:56:49 I Zone: Loading new Zone 'Everfrost'
16:56:49 D World: Classes::GetBaseClass returning base class ID: 1
16:56:49 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 2
16:56:49 D Recipes: Loaded 0 recipes for player: Scatman (2)
16:56:49 I Spawn: Loaded for zone 'Everfrost' (41):
        0 NPC(s), 0 Object(s), 0 Widget(s)
        0 Sign(s), 0 Ground Spawn(s), 0 Spawn Group(s)
        0 Spawn Group Association(s), 0 Spawn Group Chance(s)
16:56:49 D Player: Loading character_details for 'Scatman' (char_id: 2)
16:56:49 D Client: SendLoginInfo to new client...
16:56:49 D World: Increment Server_Accepted_Connection + 1
16:56:49 D Client: Populate Skill Map...
16:56:49 D Client: Toggle Character Online...
16:56:49 D Player: Toggling Character ONLINE!
16:56:49 D Client: Loading Character Skills for player 'Scatman'...
16:56:49 D Titles: Loading Titles for player 'Scatman'...
16:56:49 D Languages: Loading Languages for player 'Scatman'...
16:56:49 D Client: No character languages loaded!
16:56:49 D Spells: Loading Character Spells for player Scatman...
16:56:49 D Recipes: Loading Character Recipe Books for player 'Scatman' ...
16:56:49 D Client: No character recipe books found!
16:56:49 D Packet: Sending Login Accepted packet (LS_LoginResponse, 1008
16:56:49 D World: Send MOTD...
16:56:49 D Packet: Sending Character Macro packet (WS_MacroInit, 1008
16:56:49 D World: Sending FriendList...
16:56:49 D Player: Loading Player Factions...
16:56:49 D Player: Loading Character Quests...
16:56:50 D Player: Loading Player Mail...
16:56:50 D Client: Send Quest Journal...
16:56:50 D Client: Loading Faction Updates...
16:56:50 D Client: Send Command List...
16:56:50 D Client: Send Language Updates...
16:56:50 D Player: Getting current language for player 'Scatman'...
16:56:50 D Player:      Language ID: 0
16:56:50 D Client: SendFriendList
16:56:50 D Client: SendIgnoreList
16:56:50 D Opcode: Opcode 0056 (86): OP_QuestJournalOpenMsg, OP_QuestJournalInspectMsg
16:56:50 D Client: Found 715761960 active quests for char_id: 2
16:56:50 D Opcode: Opcode 0058 (88): Unknown in ..\..\source\WorldServer\client.cpp
16:56:50 D Opcode: OP_QuestJournalSetVisibleMsg Received 006C (108)
16:56:50 D Opcode: Opcode 009D (157): Unknown in ..\..\source\WorldServer\client.cpp
16:56:50 D Opcode: OP_ChatFiltersMsg Received 013E (318)
16:56:50 D Opcode: Opcode 009D (157): Unknown in ..\..\source\WorldServer\client.cpp
16:56:50 D Opcode: OP_ChatFiltersMsg Received 013E (318)
16:56:50 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:56:50 D Opcode: OP_Unknown Received 0026 (38)
16:56:53 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
16:56:53 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
16:56:53 D Items: Loading items for character 'Scatman' (2)
16:56:53 D Guilds: Updating Guild Member Info for Player: 2
16:56:53 D Guilds: Sent guild MOTD.
16:56:53 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
16:56:53 D Guilds: Found 4 Unique Account(s) in Guild
16:56:54 D Guilds: Sent guild member to a client.
16:56:54 D Guilds: Sent guild member.
16:56:54 D Guilds: Sent guild recruiter info to a client.
16:56:54 D Guilds: Guild::GetGuildMember: 2
16:56:54 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
16:56:54 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
16:56:54 D Guilds: Sent guild member to a client.
16:56:54 D Guilds: Sent guild member.
16:56:54 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
16:56:54 D Guilds: Sent ALL guild Events (Guild::SendAllGuildEvents).
16:56:54 D Guilds: Sent guild member list to a client.
16:56:54 D Guilds: Guild Member logged in.
16:56:54 D Player: Getting current title index for player 'Scatman'...
16:56:54 D Player:      Prefix Index: 0
16:56:54 D Player: Getting current title index for player 'Scatman'...
16:56:54 D Player:      Suffix Index: 1
16:56:54 D Merchant: Loading Buyback - Player: 2
16:56:54 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
16:56:54 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
16:56:54 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
16:56:54 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:56:54 D Opcode: OP_Unknown Received 01D4 (468)
16:56:54 D Opcode: Opcode 00ED (237): Unknown in ..\..\source\WorldServer\client.cpp
16:56:54 D Opcode: OP_GuildEventDetailsMsg Received 00FD (253)
16:56:54 D Opcode: Opcode 009D (157): Unknown in ..\..\source\WorldServer\client.cpp
16:56:54 D Opcode: OP_ChatFiltersMsg Received 013E (318)
16:56:55 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
16:56:55 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:56:55 D Opcode: OP_Unknown Received 00B3 (179)
16:56:55 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:56:55 D Opcode: Opcode 00B5 (181): OP_SignalMsg
16:56:55 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:56:55 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:56:55 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:56:55 D Command: Handler: 37, COMMAND: 'autoattack'
16:56:55 D Command: Player 'Scatman' (25), Command: autoattack
16:56:55 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:56:55 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
16:56:55 D Command: Player 'Scatman' (25), Command: enablequeuedabilities
16:57:15 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:57:15 D Command: Handler: 20, COMMAND: 'zone'
16:57:15 D Command: Player 'Scatman' (25), Command: zone
16:57:15 D World: Zone access check for oakmyst
16:57:15 D World: Grabbing zone requirements for oakmyst
16:57:15 D World: Zone Requirements: 0 status to override negative status requirement, 0 status to override level requir
ement.
16:57:15 I Zone: Loading new Zone 'oakmyst'
16:57:16 D Net: AddAuth: 25 Key: 1315267036
16:57:16 I Spawn: Loaded for zone 'oakmyst' (195):
        304 NPC(s), 86 Object(s), 2 Widget(s)
        4 Sign(s), 134 Ground Spawn(s), 8 Spawn Group(s)
        4 Spawn Group Association(s), 0 Spawn Group Chance(s)
16:57:16 D Opcode: Opcode 003A (58): OP_ReadyToZoneMsg
16:57:16 I World: OP_ReadyToZone: Player Scatman zoning to oakmyst
16:57:16 I Zone: Scheduling client 'Scatman' for removal.
16:57:16 D Player: Toggling Character OFFLINE!
16:57:17 D Net: New client from ip: 68.45.169.197 port: 50483
16:57:17 D Net: Adding new client...
16:57:17 D Client: Client::Process, ProcessQuestUpdates
16:57:17 E LUA: Error loading ZoneScripts/Oakmyst.lua:58: 'end' expected (to close 'function' at line 49) near '<eof>' (
file name: 'ZoneScripts/Oakmyst.lua')
16:57:17 E LUA: Error LUA Zone Script 'ZoneScripts/Oakmyst.lua'
16:57:17 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
16:57:17 I ZoneAuth: Access Key: 1315267036, Character Name: Scatman, Account ID: 25
16:57:17 D Player: Loading character for 'Scatman' (char_id: 2)
16:57:17 D World: Classes::GetBaseClass returning base class ID: 1
16:57:17 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 2
16:57:17 D Recipes: Loaded 0 recipes for player: Scatman (2)
16:57:17 D Player: Loading character_details for 'Scatman' (char_id: 2)
16:57:18 D Client: SendLoginInfo to new client...
16:57:18 D Client: Toggle Character Online...
16:57:18 D Player: Toggling Character ONLINE!
16:57:18 D Client: Loading Character Skills for player 'Scatman'...
16:57:18 D Titles: Loading Titles for player 'Scatman'...
16:57:18 D Languages: Loading Languages for player 'Scatman'...
16:57:18 D Client: No character languages loaded!
16:57:18 D Spells: Loading Character Spells for player Scatman...
16:57:18 D Recipes: Loading Character Recipe Books for player 'Scatman' ...
16:57:18 D Client: No character recipe books found!
16:57:18 D Packet: Sending Login Accepted packet (LS_LoginResponse, 1008
16:57:18 D Player: Loading Player Factions...
16:57:18 D Player: Loading Character Quests...
16:57:18 D Player: Loading Player Mail...
16:57:18 D Client: Send Quest Journal...
16:57:18 D Client: Loading Faction Updates...
16:57:18 D Client: Send Command List...
16:57:18 D Client: Send Language Updates...
16:57:18 D Player: Getting current language for player 'Scatman'...
16:57:18 D Player:      Language ID: 0
16:57:18 D Client: SendFriendList
16:57:18 D Client: SendIgnoreList
16:57:18 D Opcode: Opcode 0056 (86): OP_QuestJournalOpenMsg, OP_QuestJournalInspectMsg
16:57:18 D Client: Found 725618696 active quests for char_id: 2
16:57:19 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:57:19 D Opcode: OP_Unknown Received 0026 (38)
16:57:22 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
16:57:24 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
16:57:24 D Items: Loading items for character 'Scatman' (2)
16:57:24 D Guilds: Updating Guild Member Info for Player: 2
16:57:24 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
16:57:24 D Guilds: Found 4 Unique Account(s) in Guild
16:57:25 D Guilds: Sent guild recruiter info to a client.
16:57:25 D Guilds: Guild::GetGuildMember: 2
16:57:25 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
16:57:25 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
16:57:25 D Guilds: Sent guild member to a client.
16:57:25 D Guilds: Sent guild member.
16:57:25 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
16:57:25 D Guilds: Sent guild member list to a client.
16:57:25 D Guilds: Guild Member logged in.
16:57:25 D Player: Getting current title index for player 'Scatman'...
16:57:25 D Player:      Prefix Index: 0
16:57:25 D Player: Getting current title index for player 'Scatman'...
16:57:25 D Player:      Suffix Index: 1
16:57:25 D Merchant: Loading Buyback - Player: 2
16:57:25 E LUA: Error loading ZoneScripts/Oakmyst.lua:58: 'end' expected (to close 'function' at line 49) near '<eof>' (
file name: 'ZoneScripts/Oakmyst.lua')
16:57:25 E LUA: Error LUA Zone Script 'ZoneScripts/Oakmyst.lua'
16:57:25 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
16:57:25 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
16:57:25 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
16:57:25 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:57:25 D Opcode: OP_Unknown Received 00B3 (179)
16:57:26 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:57:26 D Opcode: Opcode 00B5 (181): OP_SignalMsg
16:57:26 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:57:26 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:57:26 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:57:26 D Command: Handler: 37, COMMAND: 'autoattack'
16:57:26 D Command: Player 'Scatman' (25), Command: autoattack
16:57:26 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:57:26 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
16:57:26 D Command: Player 'Scatman' (25), Command: enablequeuedabilities
16:57:27 D Player: Scatman left grid 4294967295 and entered grid 490620286
16:57:27 E LUA: Error loading ZoneScripts/Oakmyst.lua:58: 'end' expected (to close 'function' at line 49) near '<eof>' (
file name: 'ZoneScripts/Oakmyst.lua')
16:57:34 D Player: Scatman left grid 490620286 and entered grid 1205191990
16:57:37 D Player: Scatman left grid 1205191990 and entered grid 153056220
16:57:44 D Player: Scatman left grid 153056220 and entered grid 1025670433
16:57:52 D Player: Scatman left grid 1025670433 and entered grid 1029219610
16:57:55 D Player: Scatman left grid 1029219610 and entered grid 32099134
16:57:58 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:57:58 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
16:57:58 E Command: Error, command handler of 999 was requested, but max handler is 482
16:57:58 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:57:58 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:00 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:00 D Command: Player 'Scatman' (25), Command: hail
16:58:05 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:05 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:05 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:11 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:11 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:11 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:12 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:12 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:12 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:12 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:12 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:12 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:16 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:16 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:16 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:17 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:17 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:17 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:21 D Combat: DamageType: Crush/Pierce/Slash (0)
16:58:21 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:21 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:21 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:39 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:39 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:39 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:47 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:58:47 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:47 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:58:47 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:58:47 D Command: Handler: 37, COMMAND: 'autoattack'
16:58:47 D Command: Player 'Scatman' (25), Command: autoattack
16:59:12 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:13 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:59:13 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:59:13 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:59:14 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:16 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:18 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:20 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:22 D Combat: DamageType: Crush/Pierce/Slash (0)
16:59:24 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:59:24 D Command: Player 'Scatman' (25), Command: kill
16:59:24 D World: Setting Global XP Rate to: 1.00
16:59:24 D Player: Player: Scatman earned 48 experience.
16:59:25 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
16:59:25 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:59:25 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
16:59:25 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:59:25 D Command: Handler: 37, COMMAND: 'autoattack'
16:59:25 D Command: Player 'Scatman' (25), Command: autoattack
17:02:16 I Zone: Initiating zone shutdown of 'Everfrost'
17:02:17 I Zone: Completed zone shutdown of 'Everfrost'
17:02:35 D Player: Reset Vitality > 100: 0.500000
17:02:35 D Player: Update Vitality <= 100: 0.500000
17:02:35 D Player: Update Vitality Timer: 1315267355
17:07:00 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
17:07:00 D Login: Looking for Login Appearance Updates...
17:14:24 D Opcode: Opcode 0139 (313): Unknown in ..\..\source\WorldServer\client.cpp
17:14:24 D Opcode: OP_ClientIdleBeginMsg Received 0183 (387)
17:22:00 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
17:22:00 D Login: Looking for Login Appearance Updates...
17:25:21 D Opcode: Opcode 00DC (220): OP_ExamineInfoRequestMsg
17:25:21 D Opcode: Opcode 00DC (220): OP_ExamineInfoRequestMsg
17:32:02 D Opcode: Opcode 013A (314): Unknown in ..\..\source\WorldServer\client.cpp
17:32:02 D Opcode: OP_ClientIdleEndMsg Received 0184 (388)
17:37:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
17:37:01 D Login: Looking for Login Appearance Updates...
17:52:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
17:52:01 D Login: Looking for Login Appearance Updates...
18:01:17 D Opcode: Opcode 0139 (313): Unknown in ..\..\source\WorldServer\client.cpp
18:01:17 D Opcode: OP_ClientIdleBeginMsg Received 0183 (387)
18:02:36 D Player: Reset Vitality > 100: 0.500000
18:02:36 D Player: Update Vitality <= 100: 0.500000
18:02:36 D Player: Update Vitality Timer: 1315270956
18:05:01 D Opcode: Opcode 2013 (8211): ServerOP_CharacterCreate
18:05:01 D World: Name check on: Imadwarf
18:05:01 D World: Response: 1
18:05:01 D Player: Adding default zone for race: 2, class: 10 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Player: Setting New Character Starting Zone to 'GreaterFaydark' FROM player choice.
18:05:01 D Player: Adding default items for race: 2, class: 10 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Player: Adding default skills for race: 2, class: 10 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Player: Adding default spells for race: 2, class: 10 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Player: Adding default skillbar for race: 2, class: 10 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Player: Adding default titles for race: 2, class: 10, gender: 1 for char_id: 57
18:05:01 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:01 D World: Classes::GetBaseClass returning base class ID: 1
18:05:01 D Guilds: Added Player: Imadwarf (57) to Guild: EQ2TC Players Guild
18:05:01 D Guilds: Auto-join player (57) to server guild 'EQ2TC Players Guild' (3) at rank 4...
18:05:05 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
18:05:05 D Net: AddAuth: 5635 Key: 1315271105
18:05:05 D Net: New client from ip: 24.253.94.141 port: 49436
18:05:05 D Net: Adding new client...
18:05:05 D Client: Client::Process, ProcessQuestUpdates
18:05:06 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
18:05:06 I ZoneAuth: Access Key: 1315271105, Character Name: Imadwarf, Account ID: 5635
18:05:06 D Player: Loading character for 'Imadwarf' (char_id: 57)
18:05:06 I Zone: Loading new Zone 'GreaterFaydark'
18:05:06 D World: Classes::GetBaseClass returning base class ID: 1
18:05:06 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 8
18:05:06 D Recipes: Loaded 0 recipes for player: Imadwarf (57)
18:05:06 D Player: Loading character_details for 'Imadwarf' (char_id: 57)
18:05:07 I Spawn: Loaded for zone 'GreaterFaydark' (114):
        3354 NPC(s), 515 Object(s), 94 Widget(s)
        134 Sign(s), 746 Ground Spawn(s), 0 Spawn Group(s)
        0 Spawn Group Association(s), 0 Spawn Group Chance(s)
18:05:27 D Client: SendLoginInfo to new client...
18:05:27 D World: Increment Server_Accepted_Connection + 1
18:05:27 D Client: Populate Skill Map...
18:05:27 D Client: Toggle Character Online...
18:05:27 D Player: Toggling Character ONLINE!
18:05:27 D Client: Loading Character Skills for player 'Imadwarf'...
18:05:27 D Titles: Loading Titles for player 'Imadwarf'...
18:05:27 D Languages: Loading Languages for player 'Imadwarf'...
18:05:27 D Client: No character languages loaded!
18:05:27 D Spells: Loading Character Spells for player Imadwarf...
18:05:27 D Recipes: Loading Character Recipe Books for player 'Imadwarf' ...
18:05:27 D Client: No character recipe books found!
18:05:27 D Packet: Sending Login Accepted packet (LS_LoginResponse, 910
18:05:27 D World: Send MOTD...
18:05:27 D Packet: Sending Character Macro packet (WS_MacroInit, 910
18:05:27 D World: Sending FriendList...
18:05:27 D Player: Loading Player Factions...
18:05:27 D Player: Loading Character Quests...
18:05:27 D Player: Loading Player Mail...
18:05:27 D Client: Send Quest Journal...
   0: 1B 00 00 00 FF 14 02 00 - 00 00 00 00 00 00 00 2C  | ...............,
  16: 3F 51 EF 08 00 49 6D 61 - 64 77 61 72 66 00 00     | ?Q...Imadwarf..
18:05:27 D Client: Loading Faction Updates...
18:05:27 D Client: Send Command List...
18:05:27 D Client: Send Language Updates...
18:05:27 D Player: Getting current language for player 'Imadwarf'...
18:05:27 D Player:      Language ID: 0
Name: num_languages     Index:  0       Type:  int8             Data:  00
Array:  Data:
Name: unknown   Index:  0       Type:  int8             Data:  00
Name: current_language          Index:  0       Type:  int8             Data:  00
18:05:27 D Client: SendFriendList
18:05:27 D Client: SendIgnoreList
18:05:27 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:05:27 D Opcode: OP_Unknown Received 0026 (38)
18:05:32 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
18:05:35 D Opcode: Opcode 00A5 (165): OP_DefaultGroupOptionsRequestMsg
18:05:47 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
18:05:47 D Items: Loading items for character 'Imadwarf' (57)
18:05:48 D Guilds: Updating Guild Member Info for Player: 57
18:05:48 D Guilds: Sent guild MOTD.
'Welcome to the EQ2Emulator Test Center Players Guild! You are welcome to stay in this guild, or remove yourself and cre
ate or join another.

This guild is for Testing purposes!'
18:05:48 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
18:05:48 D Guilds: Found 17 Unique Account(s) in Guild
18:05:48 D Guilds: Get Guild Recruiting descriptive tag index: 0, value: 0
18:05:48 D Guilds: Get Guild Recruiting descriptive tag index: 1, value: 0
18:05:48 D Guilds: Get Guild Recruiting descriptive tag index: 2, value: 0
18:05:48 D Guilds: Get Guild Recruiting descriptive tag index: 3, value: 0
18:05:48 D Guilds: Sent guild member to a client.
18:05:48 D Guilds: Sent guild member.
18:05:48 D Guilds: Sent guild recruiter info to a client.
18:05:48 D Guilds: Guild::GetGuildMember: 57
   0: 03 16 00 00 0C 00 0C 00 - 00 00 00 00 00 00 0B 00  | ................
  16: 00 00 00 00 00 00 0A 00 - 00 00 00 00 00 00 09 00  | ................
  32: 00 00 00 00 00 00 08 00 - 00 00 00 00 00 00 07 00  | ................
  48: 00 00 00 00 00 00 06 00 - 00 00 00 00 00 00 05 00  | ................
  64: 00 00 00 00 00 00 04 00 - 00 00 00 00 00 00 03 00  | ................
  80: 00 00 00 00 00 00 02 00 - 00 00 00 00 00 00 01 00  | ................
  96: 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00  | ................
 112: 00 00                                              | ..
18:05:48 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
   0: 03 16 00 00 00 00 00                               | .......
   0: 03 16 00 00 01 00 00                               | .......
   0: 03 16 00 00 02 00 00                               | .......
   0: 03 16 00 00 03 00 00                               | .......
18:05:48 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
18:05:48 D Guilds: Sent guild member to a client.
18:05:48 D Guilds: Sent guild member.
18:05:48 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
   0: 01 00 00 00 00 00 00                               | .......
   0: 01 00 00 00 01 00 00                               | .......
   0: 01 00 00 00 02 00 00                               | .......
   0: 01 00 00 00 03 00 00                               | .......
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent OLD guild Events.
18:05:48 D Guilds: Sent ALL guild Events (Guild::SendAllGuildEvents).
18:05:48 D Guilds: Sent guild member list to a client.
   0: 01 00 00 00 00 00 00 00                            | ........
   0: 01 00 00 00 01 00 00 00                            | ........
   0: 01 00 00 00 02 00 00 00                            | ........
   0: 01 00 00 00 03 00 00 00                            | ........
18:05:49 D Guilds: Guild Member logged in.
18:05:50 D Player: Getting current title index for player 'Imadwarf'...
18:05:50 D Player:      Prefix Index: -1
18:05:50 D Player: Getting current title index for player 'Imadwarf'...
18:05:50 D Player:      Suffix Index: -1
18:05:50 D Merchant: Loading Buyback - Player: 57
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00ED (237): Unknown in ..\..\source\WorldServer\client.cpp
18:05:50 D Opcode: OP_GuildEventDetailsMsg Received 00FC (252)
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:05:50 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
18:05:50 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:05:50 D Opcode: OP_Unknown Received 00B2 (178)
18:05:51 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:05:51 D Opcode: Opcode 00B5 (181): OP_SignalMsg
18:05:51 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:05:51 D Command: Player 'Imadwarf' (5635), Command: clearallqueuedabilities
18:05:51 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:05:51 D Command: Handler: 37, COMMAND: 'autoattack'
18:05:51 D Command: Player 'Imadwarf' (5635), Command: autoattack
18:05:51 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:05:51 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
18:05:51 D Command: Player 'Imadwarf' (5635), Command: enablequeuedabilities
18:06:02 D Player: Imadwarf left grid 4294967295 and entered grid 1698999948
18:06:10 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:06:10 D Command: Handler: 41, COMMAND: 'who'
18:06:10 D Command: Player 'Imadwarf' (5635), Command: who
18:07:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
18:07:01 D Login: Looking for Login Appearance Updates...
18:07:01 D Login: Found 5 Login Appearance Updates...
18:07:01 D Login: Updating `character_items` CRC in WorldDatabase::UpdateLoginEquipment
18:07:40 D Opcode: Opcode 00A2 (162): Unknown in ..\..\source\WorldServer\client.cpp
18:07:40 D Opcode: OP_SatMsg Received 00AB (171)
18:07:40 D Opcode: Opcode 001C (28): OP_RequestCampMsg
18:08:01 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
18:08:01 D Net: AddAuth: 5635 Key: 1315271281
18:08:02 D Guilds: Guild::GetGuildMember: 57
18:08:02 D Guilds: Sent guild member to a client.
18:08:02 D Guilds: Guild Member logged out.
18:08:02 I Zone: Scheduling client 'Imadwarf' for removal.
18:08:02 D Player: Toggling Character OFFLINE!
18:08:13 D World: Removing connection...
18:08:18 D Opcode: Opcode 2012 (8210): ServerOP_BasicCharUpdate
18:08:18 D World: Delete character request: 5635 57
18:08:18 D Guilds: Look up guild ID for player ID: '57'...
18:08:46 D Opcode: Opcode 2013 (8211): ServerOP_CharacterCreate
18:08:46 D World: Name check on: Imarat
18:08:46 D World: Response: 1
18:08:46 D Player: Adding default zone for race: 13, class: 40 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Player: Setting New Character Starting Zone to 'Darklight' FROM player choice.
18:08:46 D Player: Adding default items for race: 13, class: 40 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Player: Adding default skills for race: 13, class: 40 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Player: Adding default spells for race: 13, class: 40 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Player: Adding default skillbar for race: 13, class: 40 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Player: Adding default titles for race: 13, class: 40, gender: 2 for char_id: 58
18:08:46 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:08:46 D World: Classes::GetBaseClass returning base class ID: 31
18:08:46 D Guilds: Added Player: Imarat (58) to Guild: EQ2TC Players Guild
18:08:46 D Guilds: Auto-join player (58) to server guild 'EQ2TC Players Guild' (3) at rank 4...
18:09:11 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
18:09:11 D Net: AddAuth: 5635 Key: 1315271351
18:09:11 D Net: New client from ip: 24.253.94.141 port: 49436
18:09:11 D Net: Adding new client...
18:09:11 D Client: Client::Process, ProcessQuestUpdates
18:09:12 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
18:09:12 I ZoneAuth: Access Key: 1315271351, Character Name: Imarat, Account ID: 5635
18:09:12 D Player: Loading character for 'Imarat' (char_id: 58)
18:09:12 I Zone: Loading new Zone 'Darklight'
18:09:12 D World: Classes::GetBaseClass returning base class ID: 31
18:09:12 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 38
18:09:12 D Recipes: Loaded 0 recipes for player: Imarat (58)
18:09:12 D Player: Loading character_details for 'Imarat' (char_id: 58)
18:09:13 I Spawn: Loaded for zone 'Darklight' (34):
        1218 NPC(s), 37 Object(s), 46 Widget(s)
        14 Sign(s), 913 Ground Spawn(s), 0 Spawn Group(s)
        0 Spawn Group Association(s), 0 Spawn Group Chance(s)
18:09:18 D Client: SendLoginInfo to new client...
18:09:18 D World: Increment Server_Accepted_Connection + 1
18:09:18 D Client: Populate Skill Map...
18:09:18 D Client: Toggle Character Online...
18:09:18 D Player: Toggling Character ONLINE!
18:09:18 D Client: Loading Character Skills for player 'Imarat'...
18:09:19 D Titles: Loading Titles for player 'Imarat'...
18:09:19 D Languages: Loading Languages for player 'Imarat'...
18:09:19 D Client: No character languages loaded!
18:09:19 D Spells: Loading Character Spells for player Imarat...
18:09:19 D Recipes: Loading Character Recipe Books for player 'Imarat' ...
18:09:19 D Client: No character recipe books found!
18:09:19 D Packet: Sending Login Accepted packet (LS_LoginResponse, 910
18:09:19 D World: Send MOTD...
18:09:19 D Packet: Sending Character Macro packet (WS_MacroInit, 910
18:09:19 D World: Sending FriendList...
18:09:19 D Player: Loading Player Factions...
18:09:19 D Player: Loading Character Quests...
18:09:19 D Player: Loading Player Mail...
18:09:19 D Client: Send Quest Journal...
   0: 19 00 00 00 FF 14 02 00 - 00 00 00 00 00 00 00 9F  | ................
  16: 75 A9 F8 06 00 49 6D 61 - 72 61 74 00 00           | u....Imarat..
18:09:19 D Client: Loading Faction Updates...
18:09:19 D Client: Send Command List...
18:09:19 D Client: Send Language Updates...
18:09:19 D Player: Getting current language for player 'Imarat'...
18:09:19 D Player:      Language ID: 0
Name: num_languages     Index:  0       Type:  int8             Data:  00
Array:  Data:
Name: unknown   Index:  0       Type:  int8             Data:  00
Name: current_language          Index:  0       Type:  int8             Data:  00
18:09:19 D Client: SendFriendList
18:09:19 D Client: SendIgnoreList
18:09:19 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:09:19 D Opcode: OP_Unknown Received 0026 (38)
18:09:23 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
18:09:53 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
18:09:53 D Items: Loading items for character 'Imarat' (58)
18:09:54 D Guilds: Updating Guild Member Info for Player: 58
18:09:54 D Guilds: Sent guild MOTD.
'Welcome to the EQ2Emulator Test Center Players Guild! You are welcome to stay in this guild, or remove yourself and cre
ate or join another.

This guild is for Testing purposes!'
18:09:54 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
18:09:54 D Guilds: Found 17 Unique Account(s) in Guild
18:09:54 D Guilds: Get Guild Recruiting descriptive tag index: 0, value: 0
18:09:54 D Guilds: Get Guild Recruiting descriptive tag index: 1, value: 0
18:09:54 D Guilds: Get Guild Recruiting descriptive tag index: 2, value: 0
18:09:54 D Guilds: Get Guild Recruiting descriptive tag index: 3, value: 0
18:09:54 D Guilds: Sent guild member to a client.
18:09:54 D Guilds: Sent guild member to a client.
18:09:54 D Guilds: Sent guild member.
18:09:54 D Guilds: Sent guild recruiter info to a client.
18:09:54 D Guilds: Guild::GetGuildMember: 58
   0: 03 16 00 00 0C 00 0C 00 - 00 00 00 00 00 00 0B 00  | ................
  16: 00 00 00 00 00 00 0A 00 - 00 00 00 00 00 00 09 00  | ................
  32: 00 00 00 00 00 00 08 00 - 00 00 00 00 00 00 07 00  | ................
  48: 00 00 00 00 00 00 06 00 - 00 00 00 00 00 00 05 00  | ................
  64: 00 00 00 00 00 00 04 00 - 00 00 00 00 00 00 03 00  | ................
  80: 00 00 00 00 00 00 02 00 - 00 00 00 00 00 00 01 00  | ................
  96: 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00  | ................
 112: 00 00                                              | ..
18:09:54 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
   0: 03 16 00 00 00 00 00                               | .......
   0: 03 16 00 00 01 00 00                               | .......
   0: 03 16 00 00 02 00 00                               | .......
   0: 03 16 00 00 03 00 00                               | .......
18:09:54 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
18:09:54 D Guilds: Sent guild member to a client.
18:09:54 D Guilds: Sent guild member to a client.
18:09:54 D Guilds: Sent guild member.
18:09:54 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
   0: 01 00 00 00 00 00 00                               | .......
   0: 01 00 00 00 01 00 00                               | .......
   0: 01 00 00 00 02 00 00                               | .......
   0: 01 00 00 00 03 00 00                               | .......
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent OLD guild Events.
18:09:55 D Guilds: Sent ALL guild Events (Guild::SendAllGuildEvents).
18:09:55 D Guilds: Sent guild member list to a client.
   0: 01 00 00 00 00 00 00 00                            | ........
   0: 01 00 00 00 01 00 00 00                            | ........
   0: 01 00 00 00 02 00 00 00                            | ........
   0: 01 00 00 00 03 00 00 00                            | ........
18:09:55 D Guilds: Guild Member logged in.
18:09:55 D Player: Getting current title index for player 'Imarat'...
18:09:55 D Player:      Prefix Index: -1
18:09:55 D Player: Getting current title index for player 'Imarat'...
18:09:55 D Player:      Suffix Index: -1
18:09:55 D Merchant: Loading Buyback - Player: 58
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00ED (237): Unknown in ..\..\source\WorldServer\client.cpp
18:09:55 D Opcode: OP_GuildEventDetailsMsg Received 00FC (252)
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:55 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
*** Future packet: Expecting Seq=32, but got Seq=33

*** Processing Future packet: Seq=33

18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:09:58 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
18:09:58 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:09:58 D Opcode: OP_Unknown Received 00B2 (178)
18:09:59 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:09:59 D Opcode: Opcode 00B5 (181): OP_SignalMsg
18:09:59 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:09:59 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:09:59 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:09:59 D Command: Handler: 37, COMMAND: 'autoattack'
18:09:59 D Command: Player 'Imarat' (5635), Command: autoattack
18:09:59 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:09:59 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
18:09:59 D Command: Player 'Imarat' (5635), Command: enablequeuedabilities
18:10:10 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:10:10 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
18:10:10 D Command: Player 'Imarat' (5635), Command: hail
18:10:10 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:10:10 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:10:32 D Player: Imarat left grid 4294967295 and entered grid 2836440280
18:10:34 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:10:34 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
18:10:34 D Command: Player 'Imarat' (5635), Command: hail
18:10:34 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:10:34 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:13:02 I Zone: Initiating zone shutdown of 'GreaterFaydark'
18:13:09 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:13:09 D Opcode: Opcode 008F (143): OP_EntityVerbsRequestMsg
18:13:09 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:13:09 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:13:10 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
18:13:10 D Command: Player 'Imarat' (5635), Command: hail
18:13:12 D Opcode: Opcode 0051 (81): OP_DialogSelectMsg
18:13:13 D Opcode: Opcode 0051 (81): OP_DialogSelectMsg
18:13:16 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:13:16 D Command: Handler: 76, COMMAND: 'q_accept_pending_quest'
18:13:16 D Command: Player 'Imarat' (5635), Command: q_accept_pending_quest
18:13:16 D Client: Found 1088785272 pending quests for char_id: 75
18:13:16 D LUA: Quest: Elemental Encroachment, function: Accepted
18:13:16 D LUA: Done!
18:13:16 D Client: Send Quest Journal...
   0: 62 00 00 00 FF 14 02 01 - 00 01 16 00 45 6C 65 6D  | b...........Elem
  16: 65 6E 74 61 6C 20 45 6E - 63 72 6F 61 63 68 6D 65  | ental Encroachme
  32: 6E 74 0E 00 44 61 72 6B - 6C 69 67 68 74 20 57 6F  | nt..Darklight Wo
  48: 6F 64 0E 00 44 61 72 6B - 6C 69 67 68 74 20 57 6F  | od..Darklight Wo
  64: 6F 64 00 00 00 00 00 4B - 00 00 00 05 09 0B 01 04  | od.....K........
  80: 03 01 00 00 4B 00 00 00 - 9F 75 A9 F8 06 00 49 6D  | ....K....u....Im
  96: 61 72 61 74 01 00                                  | arat..
18:13:17 D Opcode: Opcode 0056 (86): OP_QuestJournalOpenMsg, OP_QuestJournalInspectMsg
18:13:17 D Client: Found 1088785272 active quests for char_id: 58
18:13:17 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:13:17 D Opcode: OP_Unknown Received 01D2 (466)
18:13:20 I Zone: Completed zone shutdown of 'GreaterFaydark'
18:15:05 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:15:05 D Opcode: Opcode 008F (143): OP_EntityVerbsRequestMsg
18:15:05 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:15:05 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:15:06 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
18:15:06 D Command: Player 'Imarat' (5635), Command: hail
18:15:11 D Opcode: Opcode 0051 (81): OP_DialogSelectMsg
18:21:33 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:21:33 D Opcode: Opcode 0091 (145): OP_EntityVerbsVerbMsg
18:21:33 D Command: Player 'Imarat' (5635), Command: hail
18:21:33 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:21:33 D Command: Player 'Imarat' (5635), Command: clearallqueuedabilities
18:22:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
18:22:01 D Login: Looking for Login Appearance Updates...
18:22:01 D Login: Found 5 Login Appearance Updates...
18:22:01 D Login: Updating `character_items` CRC in WorldDatabase::UpdateLoginEquipment
18:28:41 D Opcode: Opcode 013A (314): Unknown in ..\..\source\WorldServer\client.cpp
18:28:41 D Opcode: OP_ClientIdleEndMsg Received 0184 (388)
18:28:44 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:28:44 D Command: Handler: 20, COMMAND: 'zone'
18:28:44 D Command: Player 'Scatman' (25), Command: zone
18:28:44 D World: Zone access check for outpostoverlord
18:28:44 D World: Grabbing zone requirements for outpostoverlord
18:28:44 D World: Zone Requirements: 0 status to override negative status requirement, 0 status to override level requir
ement.
18:28:44 I Zone: Loading new Zone 'outpostoverlord'
18:28:44 D Net: AddAuth: 25 Key: 1315272524
18:28:45 I Spawn: Loaded for zone 'outpostoverlord' (278):
        1122 NPC(s), 165 Object(s), 1 Widget(s)
        41 Sign(s), 386 Ground Spawn(s), 74 Spawn Group(s)
        24 Spawn Group Association(s), 2 Spawn Group Chance(s)
18:28:45 D Opcode: Opcode 003A (58): OP_ReadyToZoneMsg
18:28:45 I World: OP_ReadyToZone: Player Scatman zoning to outpostoverlord
18:28:45 I Zone: Scheduling client 'Scatman' for removal.
18:28:45 D Player: Toggling Character OFFLINE!
18:28:46 D Net: New client from ip: 68.45.169.197 port: 50483
18:28:46 D Net: Adding new client...
18:28:46 D Client: Client::Process, ProcessQuestUpdates
18:28:47 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
18:28:47 I ZoneAuth: Access Key: 1315272524, Character Name: Scatman, Account ID: 25
18:28:47 D Player: Loading character for 'Scatman' (char_id: 2)
18:28:47 D World: Classes::GetBaseClass returning base class ID: 1
18:28:47 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 2
18:28:47 D Recipes: Loaded 0 recipes for player: Scatman (2)
18:28:47 D Player: Loading character_details for 'Scatman' (char_id: 2)
18:28:58 D Client: SendLoginInfo to new client...
18:28:58 D Client: Toggle Character Online...
18:28:58 D Player: Toggling Character ONLINE!
18:28:58 D Client: Loading Character Skills for player 'Scatman'...
18:28:58 D Titles: Loading Titles for player 'Scatman'...
18:28:58 D Languages: Loading Languages for player 'Scatman'...
18:28:58 D Client: No character languages loaded!
18:28:58 D Spells: Loading Character Spells for player Scatman...
18:28:58 D Recipes: Loading Character Recipe Books for player 'Scatman' ...
18:28:58 D Client: No character recipe books found!
18:28:58 D Packet: Sending Login Accepted packet (LS_LoginResponse, 1008
18:28:58 D Player: Loading Player Factions...
18:28:58 D Player: Loading Character Quests...
18:28:58 D Player: Loading Player Mail...
18:28:58 D Client: Send Quest Journal...
   0: 79 00 00 00 FF 30 02 01 - 00 0E 00 51 75 65 65 6E  | y....0.....Queen
  16: 27 73 20 43 6F 6C 6F 6E - 79 00 00 02 00 01 11 00  | 's Colony.......
  32: 54 68 65 20 41 72 74 20 - 6F 66 20 43 6F 6D 62 61  | The Art of Comba
  48: 74 00 00 00 00 54 06 00 - 00 00 02 08 0B 01 04 02  | t....T..........
  64: 01 00 01 12 00 41 20 50 - 72 65 73 65 6E 63 65 20  | .....A Presence
  80: 6F 66 20 45 76 69 6C 00 - 00 00 00 50 07 00 00 00  | of Evil....P....
  96: 02 08 0B 02 04 03 00 00 - 00 00 07 00 00 00 A3 89  | ................
 112: 22 28 07 00 53 63 61 74 - 6D 61 6E 01 00           | "(..Scatman..
18:28:58 D Client: Loading Faction Updates...
18:28:58 D Client: Send Command List...
18:28:58 D Client: Send Language Updates...
18:28:58 D Player: Getting current language for player 'Scatman'...
18:28:58 D Player:      Language ID: 0
Name: num_languages     Index:  0       Type:  int8             Data:  00
Array:  Data:
Name: unknown   Index:  0       Type:  int8             Data:  00
Name: current_language          Index:  0       Type:  int8             Data:  00
18:28:58 D Client: SendFriendList
18:28:58 D Client: SendIgnoreList
18:28:58 D Opcode: Opcode 0056 (86): OP_QuestJournalOpenMsg, OP_QuestJournalInspectMsg
18:28:58 D Client: Found 816127304 active quests for char_id: 2
18:29:07 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:29:07 D Opcode: OP_Unknown Received 0026 (38)
18:29:07 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
18:29:46 D Opcode: Opcode 00B5 (181): OP_SignalMsg
18:30:09 D Opcode: Opcode 00A2 (162): Unknown in ..\..\source\WorldServer\client.cpp
18:30:09 D Opcode: OP_SatMsg Received 00AB (171)
18:30:09 D Opcode: Opcode 001C (28): OP_RequestCampMsg
18:30:31 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
18:30:31 D Net: AddAuth: 5635 Key: 1315272631
18:30:32 D Guilds: Guild::GetGuildMember: 58
18:30:32 D Guilds: Sent guild member to a client.
18:30:32 D Guilds: Guild Member logged out.
18:30:32 I Zone: Scheduling client 'Imarat' for removal.
18:30:32 D Player: Toggling Character OFFLINE!
18:30:40 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
18:30:40 D Items: Loading items for character 'Scatman' (2)
18:30:45 D World: Removing connection...
18:30:48 D Guilds: Updating Guild Member Info for Player: 2
18:30:48 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
18:30:48 D Guilds: Found 4 Unique Account(s) in Guild
18:30:48 D Guilds: Get Guild Recruiting descriptive tag index: 0, value: 0
18:30:48 D Guilds: Get Guild Recruiting descriptive tag index: 1, value: 0
18:30:48 D Guilds: Get Guild Recruiting descriptive tag index: 2, value: 0
18:30:48 D Guilds: Get Guild Recruiting descriptive tag index: 3, value: 0
18:30:48 D Guilds: Sent guild recruiter info to a client.
18:30:48 D Guilds: Guild::GetGuildMember: 2
   0: 19 00 00 00 07 00 0A 00 - 00 00 00 00 00 00 09 00  | ................
  16: 00 00 00 00 00 00 08 00 - 00 00 00 00 00 00 07 00  | ................
  32: 00 00 00 00 00 00 06 00 - 00 00 00 00 00 00 05 00  | ................
  48: 00 00 00 00 00 00 01 00 - 00 00 00 00 00 00 00 00  | ................
  64: 00 00 00 00 00                                     | .....
18:30:48 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
   0: 19 00 00 00 00 00 00                               | .......
   0: 19 00 00 00 01 00 00                               | .......
   0: 19 00 00 00 02 00 00                               | .......
   0: 19 00 00 00 03 00 00                               | .......
18:30:48 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
18:30:48 D Guilds: Sent guild member to a client.
18:30:48 D Guilds: Sent guild member.
18:30:48 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
   0: 01 00 00 00 00 00 00                               | .......
   0: 01 00 00 00 01 00 00                               | .......
   0: 01 00 00 00 02 00 00                               | .......
   0: 01 00 00 00 03 00 00                               | .......
18:30:48 D Guilds: Sent guild member list to a client.
18:30:49 D Guilds: Guild Member logged in.
18:30:49 D Player: Getting current title index for player 'Scatman'...
18:30:49 D Player:      Prefix Index: 0
18:30:49 D Player: Getting current title index for player 'Scatman'...
18:30:49 D Player:      Suffix Index: 1
18:30:49 D Merchant: Loading Buyback - Player: 2
18:30:49 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
18:30:49 D Opcode: Opcode 00B5 (181): OP_SignalMsg
18:30:49 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:30:49 D Opcode: Opcode 00B6 (182): OP_SkillInfoRequest
18:30:49 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
18:30:49 D Opcode: OP_Unknown Received 00B3 (179)
18:30:50 D Opcode: Opcode 0029 (41): OP_UpdateTargetMsg
18:30:50 D Opcode: Opcode 00B5 (181): OP_SignalMsg
18:30:50 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:30:50 D Command: Player 'Scatman' (25), Command: clearallqueuedabilities
18:30:50 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:30:50 D Command: Handler: 37, COMMAND: 'autoattack'
18:30:50 D Command: Player 'Scatman' (25), Command: autoattack
18:30:50 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:30:50 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
18:30:50 D Command: Player 'Scatman' (25), Command: enablequeuedabilities
18:33:45 I Zone: Initiating zone shutdown of 'oakmyst'
18:33:49 I Zone: Completed zone shutdown of 'oakmyst'
18:35:32 I Zone: Initiating zone shutdown of 'Darklight'
18:35:39 I Zone: Completed zone shutdown of 'Darklight'
18:37:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
18:37:01 D Login: Looking for Login Appearance Updates...
18:43:44 D Opcode: Opcode 0139 (313): Unknown in ..\..\source\WorldServer\client.cpp
18:43:44 D Opcode: OP_ClientIdleBeginMsg Received 0183 (387)
18:52:01 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
18:52:01 D Login: Looking for Login Appearance Updates...
18:52:30 D Opcode: Opcode 013A (314): Unknown in ..\..\source\WorldServer\client.cpp
18:52:30 D Opcode: OP_ClientIdleEndMsg Received 0184 (388)
18:52:30 D Player: Scatman left grid 4294967295 and entered grid 924281492
18:52:43 D Player: Scatman left grid 924281492 and entered grid 771354606
18:55:26 D Player: Scatman left grid 771354606 and entered grid 3146737430
18:56:39 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
18:56:39 D Command: Handler: 20, COMMAND: 'zone'
18:56:39 D Command: Player 'Scatman' (25), Command: zone
18:56:39 D World: Zone access check for ruins
18:56:39 D World: Grabbing zone requirements for ruins
18:56:39 D World: Zone Requirements: 0 status to override negative status requirement, 0 status to override level requir
ement.
18:56:39 I Zone: Loading new Zone 'ruins'
18:56:39 I Spawn: Loaded for zone 'ruins' (127):
        304 NPC(s), 28 Object(s), 1 Widget(s)
        13 Sign(s), 36 Ground Spawn(s), 159 Spawn Group(s)
        81 Spawn Group Association(s), 0 Spawn Group Chance(s)
18:57:46 D World: Removing connection...
18:58:25 D Opcode: Opcode AB00 (43776): ServerOP_UsertoWorldReq
18:58:25 D Net: AddAuth: 25 Key: 1315274305
18:58:26 D Net: New client from ip: 68.45.169.197 port: 62282
18:58:26 D Net: Adding new client...
18:58:26 D Client: Client::Process, ProcessQuestUpdates
18:58:26 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
18:58:26 I ZoneAuth: Access Key: 1315274305, Character Name: Scatman, Account ID: 25
18:58:26 D Player: Loading character for 'Scatman' (char_id: 2)
18:58:26 D World: Classes::GetBaseClass returning base class ID: 1
18:58:26 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 2
19:01:40 I Zone: Initiating zone shutdown of 'ruins'
19:01:41 I Zone: Completed zone shutdown of 'ruins'

User avatar
xinux
Team Member
Posts: 680
Joined: Wed Mar 10, 2010 11:10 am
Location: Destroyer of Servers

Re: Critical: World slowness, zone & client issues, blah.

Post by xinux » Tue Sep 06, 2011 3:12 pm

Humm would love to try and replicate this on my box but would need the current TC updated to the svn.


also adding this link as a reference since it seem's to be the same issue.


http://www.eq2emulator.net/phpBB3/viewt ... ons#p21547
EQ II - Build=1360 (Orig) - Build=1360 (DoF) - Build=2654 (KoS) - Build=3375 (Classic) - Build=3554 (EoF)
EQ II - Build=4412 (RoK) - Build=5122 (TSO) - Build=6118 (SF) - Build=7628 (DoV) - Build=8295 (Aod)

User avatar
Sylva1n
Posts: 271
Joined: Tue Mar 24, 2009 3:03 pm
Location: Quebec, Canada

Re: Critical: World slowness, zone & client issues, blah.

Post by Sylva1n » Tue Sep 06, 2011 3:37 pm

had a hard time just login in last night was getting "server did not respond in a timely manner" over and over again

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Tue Sep 06, 2011 4:03 pm

xinux wrote:Humm would love to try and replicate this on my box but would need the current TC updated to the svn.
Dood, what is on TC is no different than what is on SVN. The patcher is updated, and will bring the SVN DB to current code specs.

You got all the data I got ;)

User avatar
xinux
Team Member
Posts: 680
Joined: Wed Mar 10, 2010 11:10 am
Location: Destroyer of Servers

Re: Critical: World slowness, zone & client issues, blah.

Post by xinux » Tue Sep 06, 2011 4:49 pm

Was thinking more along the lines of the source to recompile the exe but forgot that you did that yesterday. sorry :oops:
EQ II - Build=1360 (Orig) - Build=1360 (DoF) - Build=2654 (KoS) - Build=3375 (Classic) - Build=3554 (EoF)
EQ II - Build=4412 (RoK) - Build=5122 (TSO) - Build=6118 (SF) - Build=7628 (DoV) - Build=8295 (Aod)

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Tue Sep 06, 2011 5:48 pm

Ahh, no worries. Yes, it was committed to our (private) SVN and migrated to the public ones this morning. You should have all the good stuff now.

Hopefully you'll get slowness, crashy behavior too, and maybe stumble upon the issue. I am out of ideas :(

User avatar
Scatman
Retired
Posts: 1688
Joined: Wed Apr 16, 2008 5:44 am
EQ2Emu Server: Scatman's Word
Characters: Scatman
Location: New Jersey

Re: Critical: World slowness, zone & client issues, blah.

Post by Scatman » Tue Sep 06, 2011 6:10 pm

Were there any patterns you saw? I've been sitting in QC doing a /who all but no delays. Figured this would be the easier of the two to track down..

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Tue Sep 06, 2011 6:27 pm

Xinux said in IRC that TC was running slow, and I looked over the CPU/Memory/Disk I/O in perfmon, and saw the VM idle. I thought he was having internet issues, til I attempted to log in, and timed out on my first try (entity loading was 1 entity per second til I crashed). I immediately reconnected, and got right in. But, in QC, /who all took 10+ seconds.

I zoned to GMHall and zoned pretty fast, and commands were fine. However, back in QC, things were slow still. So it was a zone-only thing, and has to be related to everything else we're seeing.

I've almost positive now that maybe I left off an important { } somewhere when removing hundreds of lines of logging ;) What else can it possibly be? Can't be titles, languages, and that's all that has been added in months.

User avatar
xinux
Team Member
Posts: 680
Joined: Wed Mar 10, 2010 11:10 am
Location: Destroyer of Servers

Re: Critical: World slowness, zone & client issues, blah.

Post by xinux » Tue Sep 06, 2011 10:44 pm

Well i got my world server to lock twice so far after logging in and out, deleting, recreating etc etc nothing. so i decided to force close the client and log back in after i logged back into the game server stopped responding to all commands on both characters. Well at this time i turned on pretty much everything in the log file (wish i had before this lol).
Ok the second time i had a character just sitting in queen's colony (slasher) and was messing with the other account after a few force close on that one client and nothing i logged that character off and created a new character in Darklight then my character in queen's colony (slasher) stopped responding i forced closed the client and logged back in but it will not let me rejoin. This whole time my new character in Darklight is fine until i went to zone the he locked and no other logging was going on until i closed the client then it popped up with the following.

22:42:21 I Zone: Initiating zone shutdown of 'TimorousDeep'
22:42:22 I Zone: Completed zone shutdown of 'TimorousDeep'
22:42:22 I Zone: Initiating zone shutdown of 'Darklight'
22:42:23 I Zone: Completed zone shutdown of 'Darklight'

Looking at SQL it still shows (slasher) as being logged in but it actually logged my new character out according to the DB and it's currently reading 2 clients in 1 zone.

not sure if this will help but here is the logs i'll try and format them while i'm at work tomorrow and since i can't attach files here is a link.


http://toxic04.com/patch/log/
EQ II - Build=1360 (Orig) - Build=1360 (DoF) - Build=2654 (KoS) - Build=3375 (Classic) - Build=3554 (EoF)
EQ II - Build=4412 (RoK) - Build=5122 (TSO) - Build=6118 (SF) - Build=7628 (DoV) - Build=8295 (Aod)

User avatar
Sylva1n
Posts: 271
Joined: Tue Mar 24, 2009 3:03 pm
Location: Quebec, Canada

Re: Critical: World slowness, zone & client issues, blah.

Post by Sylva1n » Wed Sep 07, 2011 4:06 am

ain't it possible to rollback and just re-add titles and languages to see if it fixes the problem (locally if need be) ?

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Wed Sep 07, 2011 11:29 am

This has been happening long before titles and languages were added. It's just more obvious now, and I more suspect my added logging efforts than anything else, because in reality, not that much has changed in months... since Collections, ground spawn revamps, etc.

Of course it's simple to revert to an older SVN (checkout specific version) and compile, run. They are all there, all 1400+ of them :)

User avatar
xinux
Team Member
Posts: 680
Joined: Wed Mar 10, 2010 11:10 am
Location: Destroyer of Servers

Re: Critical: World slowness, zone & client issues, blah.

Post by xinux » Thu Sep 08, 2011 1:31 am

Man tried for awhile tonight to lock my server and couldn't even had it up to almost 10 clients. Only thing that looked off was the following everytime i would move forward or back.

01:29:34 T Client: Func: Client::Process, Line: 1694, Opcode: 'OP_KnowledgeWindowSlotMappingMsg'
01:29:34 T Opcode: Opcode 010A (266): OP_KnowledgeWindowSlotMappingMsg
01:29:34 T Client: Func: Client::Process, Line: 1694, Opcode: 'OP_KnowledgeWindowSlotMappingMsg'
01:29:34 T Opcode: Opcode 010A (266): OP_KnowledgeWindowSlotMappingMsg
01:29:34 T Client: Func: Client::Process, Line: 1694, Opcode: 'OP_KnowledgeWindowSlotMappingMsg'
01:29:34 T Opcode: Opcode 010A (266): OP_KnowledgeWindowSlotMappingMsg
01:29:34 T Client: Func: Client::Process, Line: 1694, Opcode: 'OP_KnowledgeWindowSlotMappingMsg'
EQ II - Build=1360 (Orig) - Build=1360 (DoF) - Build=2654 (KoS) - Build=3375 (Classic) - Build=3554 (EoF)
EQ II - Build=4412 (RoK) - Build=5122 (TSO) - Build=6118 (SF) - Build=7628 (DoV) - Build=8295 (Aod)

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Thu Sep 08, 2011 6:40 am

If you turn on *__DETAIL logging, you are going to see every single debug entry the world executes for movement or otherwise. Something I will be changing soon in the code is implementing different levels of DEBUG/DETAIL so we can still monitor Opcode/Packets without seeing so much spam.

See examples of "#if EQDEBUG >= 9" -- this is the hard-coded level for excessive spam (not yet implemented throughout the code, still evaluating effectiveness).

User avatar
xinux
Team Member
Posts: 680
Joined: Wed Mar 10, 2010 11:10 am
Location: Destroyer of Servers

Re: Critical: World slowness, zone & client issues, blah.

Post by xinux » Thu Sep 08, 2011 10:16 am

Gotcha it just looked off since turning side to gave a movement update but going forward and backward gave a knowledgewindow response. The day i got the server to lock i wasn't running in debug mode but last night when i couldn't i was. :x
EQ II - Build=1360 (Orig) - Build=1360 (DoF) - Build=2654 (KoS) - Build=3375 (Classic) - Build=3554 (EoF)
EQ II - Build=4412 (RoK) - Build=5122 (TSO) - Build=6118 (SF) - Build=7628 (DoV) - Build=8295 (Aod)

User avatar
John Adams
Retired
Posts: 9684
Joined: Thu Jul 26, 2007 6:27 am
EQ2Emu Server: EQ2Emulator Test Center
Characters: John
Location: Arizona
Contact:

Re: Critical: World slowness, zone & client issues, blah.

Post by John Adams » Thu Sep 08, 2011 3:34 pm

Yeah, I'm not sure why the knowledge window is getting updated every step. Maybe that is not correct.

Locked

Who is online

Users browsing this forum: No registered users and 0 guests