Error Processing SpawnLoop / deadlock

[John Adams]

Just had a strange thing happen on my local server. In Antonica, completely empty zone except for me. I set my /speed 1000 and started hauling ass across the zone (was watching the fog clear on the map). Hit some water by Blackburrow and of course, started drowning immediately. Couldn't move, so I had to /zone Antonica to escape that trap. I reloaded into the same zone I was in, like normal... but within a few seconds, the zone crashed.

Here's the log from the moment I /speed 1000 til I got booted:

Code: Select all

16:37:55 D Command: Handler: 39, COMMAND: 'speed'
16:37:55 D Command: Player 'Admin' (1), Command: speed
16:37:55 D Player: Admin left grid 4294967295 and entered grid 4222594234
16:38:12 D Packet: Unable to convert EQ opcode 0X021B to an emu opcode.
16:38:12 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:38:12 D Opcode: OP_Unknown Received 021B (539)
16:38:18 D Opcode: Opcode 011C (284): OP_ClientFellMsg (ouch!)
16:38:19 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:38:19 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:12 D Packet: Unable to convert EQ opcode 0X021B to an emu opcode.
16:39:12 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:39:12 D Opcode: OP_Unknown Received 021B (539)
16:39:47 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:47 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:49 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:49 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:51 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:51 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:53 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:53 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:55 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:55 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:57 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:57 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:39:59 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:39:59 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:40:01 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:40:01 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:40:02 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:40:02 D Command: Handler: 20, COMMAND: 'zone'
16:40:02 D Command: Player 'Admin' (1), Command: zone
16:40:03 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:40:03 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:40:05 D Opcode: Opcode 010F (271): Unknown in ..\..\source\WorldServer\client.cpp
16:40:05 D Opcode: OP_UnknownNpcMsg Received 0164 (356)
16:40:05 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:40:05 D Command: Handler: 20, COMMAND: 'zone'
16:40:05 D Command: Player 'Admin' (1), Command: zone
16:40:05 D Client: Zone access check for Antonica (12), client: 1096
16:40:05 D Client: Access Requirements: status 0, level 0 - 0, req >= 0 version
16:40:05 D Client: Zone Request to 'Antonica' (757463040)
16:40:05 D Client: Client::Zone: Setting player Resurrecting to 'true'
16:40:05 D Client: Client::Zone: Removing player from fighting...
16:40:05 D Client: Client::Zone: Removing player from current zone...
16:40:05 D Client: Client::Zone: Setting zone to 'antonica'...
16:40:05 D Client: Client::Zone: Zoning player to coordinates x: 436.200012, y: -37.500000, z: 819.559998, heading: 0.00
0000 in zone 'antonica'...
16:40:05 D Client: Client::Zone: Saving Player info...
16:40:05 D Client: Client::Zone: New Zone IP '127.0.0.1'...
16:40:05 D Client: Client::Zone: Sending ZoneChangeMsg...
16:40:05 D Client: Client::Zone: Seralizing Zone Change request...
16:40:05 D Client: Client::Zone: Sending to zone_auth.AddAuth...
16:40:05 D Net: AddAuth: 1 Key: 1330299605
16:40:06 D Opcode: Opcode 003A (58): OP_ReadyToZoneMsg
16:40:06 I World: OP_ReadyToZone: Player Admin zoning to antonica
16:40:06 I Zone: Scheduling client 'Admin' for removal.
16:40:06 D Player: Toggling Character OFFLINE!
16:40:06 D Net: New client from ip: 127.0.0.1 port: 51841
16:40:06 D Net: Adding new client...
16:40:06 D Client: Client::Process, ProcessQuestUpdates
16:40:07 D Opcode: Opcode 0002 (2): OP_LoginByNumRequestMsg
16:40:07 I ZoneAuth: Access Key: 1330299605, Character Name: Admin, Account ID: 1
16:40:07 D Player: Loading character for 'Admin' (char_id: 1)
16:40:07 D World: Classes::GetBaseClass returning base class ID: 1
16:40:07 D World: Classes::GetSecondaryBaseClass returning secondary class ID: 2
16:40:07 D Recipes: Loaded 0 recipes for player: Admin (1)
16:40:07 D Player: Loading character_details for 'Admin' (char_id: 1)
16:40:07 D Client: SendLoginInfo to new client...
16:40:07 D Client: Toggle Character Online...
16:40:07 D Player: Toggling Character ONLINE!
16:40:07 D Client: Loading Character Skills for player 'Admin'...
16:40:07 D World: Loading Titles for player 'Admin'...
16:40:07 D World: Loading Languages for player 'Admin'...
16:40:07 D Client: No character languages loaded!
16:40:07 D Spells: Loading Character Spells for player Admin...
16:40:07 D Recipes: Loading Character Recipe Books for player 'Admin' ...
16:40:07 D Client: No character recipe books found!
16:40:07 D Packet: Sending Login Accepted packet (LS_LoginResponse, 1096)
16:40:07 D Player: Loading Player Factions...
16:40:07 D Player: Loading Character Quests...
16:40:07 D Player: Loading Player Mail...
16:40:07 D Client: Send Quest Journal...
16:40:07 D Client: Loading Faction Updates...
16:40:07 D Client: Send Command List...
16:40:07 D Client: Send Language Updates...
16:40:07 D Player: Getting current language for player 'Admin'...
16:40:07 D Player:      Language ID: 0
16:40:07 D Client: SendFriendList
16:40:07 D Client: SendIgnoreList
16:40:07 D Packet: Unable to convert EQ opcode 0X0027 to an emu opcode.
16:40:07 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:40:07 D Opcode: OP_Unknown Received 0027 (39)
16:40:10 D Opcode: Opcode 000D (13): OP_DoneLoadingZoneResourcesMsg
16:40:11 D Opcode: Opcode 0085 (133): Unknown in ..\..\source\WorldServer\client.cpp
16:40:11 D Opcode: OP_UIResetMsg Received 00A0 (160)
16:40:11 D Opcode: Opcode 000F (15): OP_DoneLoadingEntityResourcesMsg
16:40:11 D Items: Loading items for character 'Admin' (1)
16:40:11 D Guilds: Updating Guild Member Info for Player: 1
16:40:11 D Guilds: SendGuildUpdate to client online... (Guild::SendGuildUpdate)
16:40:11 D Guilds: Found 1 Unique Account(s) in Guild
16:40:11 D Guilds: Get Guild Recruiting descriptive tag index: 0, value: 3
16:40:11 D Guilds: Get Guild Recruiting descriptive tag index: 1, value: 0
16:40:11 D Guilds: Get Guild Recruiting descriptive tag index: 2, value: 0
16:40:11 D Guilds: Get Guild Recruiting descriptive tag index: 3, value: 0
16:40:11 D Guilds: Sent guild recruiter info to a client.
16:40:11 D Guilds: Guild::GetGuildMember: 1
16:40:11 D Guilds: Sent guild Event List (Guild::SendGuildEventList).
16:40:11 D Guilds: Sent guild bank events list. (Guild::SendGuildBankEventList)
16:40:11 D Guilds: Sent guild member to a client.
16:40:11 D Guilds: Sent guild member.
16:40:11 D Guilds: Sent guild Event Details (Guild::SendGuildEventDetails).
16:40:11 D Guilds: Sent guild member list to a client.
16:40:11 D Guilds: Guild Member logged in.
16:40:11 D Player: Getting current title index for player 'Admin'...
16:40:11 D Player:      Prefix Index: -1
16:40:11 D Player: Getting current title index for player 'Admin'...
16:40:11 D Player:      Suffix Index: -1
16:40:11 D Merchant: Loading Buyback - Player: 1
16:40:12 D Opcode: Opcode 0010 (16): OP_PredictionUpdateMsg
16:40:12 D Packet: Unable to convert EQ opcode 0X00B6 to an emu opcode.
16:40:12 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:40:12 D Opcode: OP_Unknown Received 00B6 (182)
16:40:12 D Packet: Unable to convert EQ opcode 0X021B to an emu opcode.
16:40:12 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:40:12 D Opcode: OP_Unknown Received 021B (539)
16:40:12 D Opcode: Opcode 00B5 (181): OP_SignalMsg
16:40:12 D Packet: Unable to convert EQ opcode 0X021D to an emu opcode.
16:40:12 D Opcode: Opcode 0000 (0): Unknown in ..\..\source\WorldServer\client.cpp
16:40:12 D Opcode: OP_Unknown Received 021D (541)
16:40:12 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:40:12 D Command: Handler: 37, COMMAND: 'autoattack'
16:40:12 D Command: Player 'Admin' (1), Command: autoattack
16:40:12 D Opcode: Opcode 0011 (17): OP_RemoteCmdMsg
16:40:12 D Command: Handler: 35, COMMAND: 'enablequeuedabilities'
16:40:12 D Command: Player 'Admin' (1), Command: enablequeuedabilities
16:40:37 E Zone: Error Processing SpawnLoop, shutting down zone 'antonica'...
16:40:37 D Guilds: Guild::GetGuildMember: 1
16:40:37 D Guilds: Sent guild member to a client.
16:40:37 D Guilds: Guild Member logged out.
16:40:37 I Zone: Scheduling client 'Admin' for removal.
16:40:37 D Player: Toggling Character OFFLINE!
16:40:37 I Zone: Initiating zone shutdown of 'antonica'
16:40:47 D World: Removing connection...
16:40:56 I Zone: Initiating zone shutdown of 'GMHall'
16:40:56 I Zone: Completed zone shutdown of 'GMHall'
16:40:57 E Mutex: Possible deadlock attempt by 'ZoneServer::MMasterSpawnLock'!
16:41:03 D Opcode: Opcode 201D (8221): ServerOP_LoginEquipment
16:41:03 D Login: Looking for Login Appearance Updates...

Not blaming Guilds yet, but I do this kind of thing constantly, re-zoning into my current zone. Just before doing this, I had created a Guild and made myself the leader, poked around a few of those screens including Recruiting. I don't *think* this had any bearing, but wanted to mention it just in case. It's about the only thing different from all our other tests and this crash.

Trying to reproduce this exact crash again, then I'll remove my guild and see if it still happens. Probably just a fluke tho.


Scat, this is using your zoneserver.* changes, too. fyi.

[Scatman]

Ok cool. This is also with a few packetstruct changes too frommy linux changes. Ill look at it tomorrow.