Anyone else see these issues recently?

ProSkeptic · #1 03-08-2012, 12:20 PM

I'm doing a standard build and install of the source (svn) and everything seems to usually run ok, but I see these issues cropping up :
1 > Any player-character's death seems to clobber the zone (zone reboot) and, of course, the character's client seems to just stay there until it realizes it's disconnected (both Underfoot and Titanium were tested).

2 > Most of the time, when the server is started, quests that involve giving and/or taking items don't work (for example, handing the exterminator four whiskers (not stacked)). The deletes from player inventory shows in the log but nothing else.

Quests that involve just conversation seem to work.

Have I configured something wrong?

This is 32-bit Ubuntu 11.10, with the newest source, peqdb, maps as of yesterday.

I've done forum searches and can't find this/these.

**bad_captain** · #2 03-08-2012, 04:35 PM

1-make sure you have latest from svn and all required sql files sourced.

2- did you copy plugins into quest folder?

ProSkeptic · #3 03-08-2012, 04:46 PM

Yup.. I copied the quests to the quests subdirectory. I thought putting the full path to the quests dir in the eqemu_config.xml would be enough... but it wasn't...
I put the quests "back" up one directory instead, and it seems to work great. That's totally ok.

Will try the sql reload later.

Thanks.

ProSkeptic · #4 03-08-2012, 06:14 PM

Ok, I reloaded the database... here are the steps for that :

drop database if exists peqdb;
create database if not exists peqdb;
use peqdb;
source /home/eqemu/source/peqdatabase/drop_system.sql
source /home/eqemu/source/peqdatabase/drop_player.sql
source /home/eqemu/source/peqdatabase/drop_login.sql
source /home/eqemu/source/peqdatabase/drop_bots.sql
source /home/eqemu/source/peqdatabase/peqdb_rev2100.sql
source /home/eqemu/source/peqdatabase/load_player.sql
source /home/eqemu/source/peqdatabase/load_login.sql
source /home/eqemu/source/peqdatabase/load_bots.sql
source /home/eqemu/source/EQEmuServer/EQEmuLoginServer/login_util/EQEmuLoginServerDBInstall.sql

It's version 2100, and I think that's the newest, and there seem to be no updates beyond that.

Then I restart the server... and intentionally get killed by a rat (way harder than they appear) and get this as the only error that I can see :

eqemu_debug_3848.log:[03.08. - 17:02:44] [LAUNCHER__STATUS] Zone dynamic_05 has gone down. Restart timer started.
eqemu_debug_world.log:3847 [03.08. - 17:02:44] [WORLD__ZONELIST] Removing zoneserver #5 at :7004
eqemu_debug_world.log:3847 [03.08. - 17:02:44] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
eqemu_debug_world.log:3847 [03.08. - 17:02:44] [WORLD__LAUNCH] zone: dynamic_05 reported state STOPPED (1 starts)

sorvani · #5 03-08-2012, 06:20 PM

There is an update in the server SVN beyond DB 2100.
2104_required_group_roles.sql

Also your drop statements are redundant since you dropped the entire database first.

ProSkeptic · #6 03-08-2012, 08:49 PM

True, regarding the drop statements.

The update only mucks with the group-leaders. I reloaded the db and re-ran it (that following update 2104).

ALTER TABLE `group_leaders` ADD COLUMN `maintank` VARCHAR(64) NOT NULL DEFAULT '' AFTER `leadershipaa`;
ALTER TABLE `group_leaders` ADD COLUMN `puller` VARCHAR(64) NOT NULL DEFAULT '' AFTER `assist`;

But it didn't seem to affect the problem with a character death restarting the zone.

Should there be a place I can look? Can I activate more debugging info? Is there a log that might have more relevant information?

ProSkeptic · #7 03-09-2012, 05:28 PM

Code:

3496 [03.09. - 16:10:55] AIYellForHelp("Guard_Maddoc000","a_cave_bat001") Guard_Hobart000 attacking a_cave_bat001 Dist 369.653930 Z 5.379763
3496 [03.09. - 16:12:35] Unable to convert EQ opcode 0x6a5f to an Application opcode.
3496 [03.09. - 16:12:35] [CLIENT__NET_ERR] Kneamh: Unhandled incoming opcode: [OpCode OP_Unknown (0x6a5f) Size=8]
3496 [03.09. - 16:13:13] OP CastSpell: slot=2, spell=14, target=289, inv=ffffffff
3496 [03.09. - 16:13:15] OP CastSpell: slot=2, spell=14, target=289, inv=ffffffff
3496 [03.09. - 16:13:23] OP CastSpell: slot=2, spell=14, target=289, inv=ffffffff
3496 [03.09. - 16:13:26] OP CastSpell: slot=2, spell=14, target=289, inv=ffffffff
3496 [03.09. - 16:13:35] [CLIENT__NET_ERR] Kneamh: Unhandled incoming opcode: [OpCode OP_AnnoyingZoneUnknown (0x729c) Size=4112]
3496 [03.09. - 16:13:39] Client::AddMoneyToPP() Kneamh should have:  plat:0 gold:1 silver:28 copper:20
3496 [03.09. - 16:13:41] [INVENTORY__SLOTS] Kneamh: Putting loot item Bone Chips (13073) into slot 291
3496 [03.09. - 16:13:42] [INVENTORY__SLOTS] Kneamh: Putting loot item Gloomingdeep Silk (54234) into slot 297
3496 [03.09. - 16:13:45] [INVENTORY__SLOTS] Kneamh: Putting loot item Gloomsilk Slippers (82947) into slot 298

##############################
THIS IS WHERE THE CHARACTER IS KILLED
##############################

==> eqemu_debug_3460.log <==
[03.09. - 16:14:11] [LAUNCHER__STATUS] Zone dynamic_05 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
3451 [03.09. - 16:14:11] [WORLD__ZONELIST] Removing zoneserver #5 at :7004
3451 [03.09. - 16:14:11] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
3451 [03.09. - 16:14:11] [WORLD__LAUNCH] zone: dynamic_05 reported state STOPPED (1 starts)

==> eqemu_debug_3460.log <==
[03.09. - 16:14:21] [LAUNCHER__STATUS] Starting zone dynamic_05
[03.09. - 16:14:21] [LAUNCHER__STATUS] Zone dynamic_05 has been started.
[03.09. - 16:14:21] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.

==> eqemu_debug_world.log <==
3451 [03.09. - 16:14:21] [WORLD__LAUNCH] zone: dynamic_05 reported state STARTED (2 starts)

==> eqemu_debug_zone.log <==
---------------------------------------------
3525 [03.09. - 16:14:21] Starting Log: logs/eqemu_debug_zone.log
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading server configuration..
3525 [03.09. - 16:14:21] [ZONE__INIT] Log settings loaded from log.ini
3525 [03.09. - 16:14:21] [ZONE__INIT] Connecting to MySQL...

==> eqemu_zone.log <==
---------------------------------------------
3525 [03.09. - 16:14:21] Starting Log: logs/eqemu_zone.log
3525 [03.09. - 16:14:21] Using database 'peqdb' at 192.168.0.17:3306

==> eqemu_debug_zone.log <==
3525 [03.09. - 16:14:21] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEmulator 0.8.0
3525 [03.09. - 16:14:21] [ZONE__INIT] Log settings loaded from ./log.ini
3525 [03.09. - 16:14:21] [ZONE__INIT] Mapping Incoming Opcodes
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading Variables
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading zone names
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading items
3525 [03.09. - 16:14:21] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1217078416

==> eqemu_zone.log <==
3525 [03.09. - 16:14:21] libEMuShareMem.so loaded

==> eqemu_debug_zone.log <==
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading npc faction lists
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading loot tables
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading skill caps
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading spells
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading guilds
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading factions
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading titles
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading AA effects
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading tributes
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading corpse timers
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading commands
3525 [03.09. - 16:14:21] command_init(): - Command 'acceptrules' set to access level 0.
                ------- snipped rest of command inits
3525 [03.09. - 16:14:21] [ZONE__INIT] 247 commands loaded
3525 [03.09. - 16:14:21] [ZONE__INIT] Loaded default rule set 'default'
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading Tasks
3525 [03.09. - 16:14:21] [ZONE__INIT] Loading quests

==> eqemu_quest_zone.log <==
---------------------------------------------
3525 [03.09. - 16:14:21] Starting Log: logs/eqemu_quest_zone.log
3525 [03.09. - 16:14:21] Tying perl output to eqemu logs
3525 [03.09. - 16:14:21] Creating EQEmuIO=HASH(0x957ec24)
3525 [03.09. - 16:14:21] Creating EQEmuIO=HASH(0x956c030)
3525 [03.09. - 16:14:21] Loading perlemb plugins.
3525 [03.09. - 16:14:22] Loading perl commands...

==> eqemu_debug_zone.log <==
3525 [03.09. - 16:14:22] [ZONE__INIT] Entering sleep mode

==> eqemu_debug_world.log <==
3451 [03.09. - 16:14:22] [WORLD__ZONE] New TCP connection from 192.168.0.17:44937

==> eqemu_debug_zone.log <==
3525 [03.09. - 16:14:22] [NET__IDENTIFY] Registered patch 6.2
3525 [03.09. - 16:14:22] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1360704656
3525 [03.09. - 16:14:22] [NET__IDENTIFY] Registered patch Titanium
3525 [03.09. - 16:14:22] [NET__IDENTIFY] Registered patch SoF
3525 [03.09. - 16:14:22] [NET__IDENTIFY] Registered patch SoD
3525 [03.09. - 16:14:22] [NET__IDENTIFY] Registered patch Underfoot
3525 [03.09. - 16:14:22] [COMMON__THREADS] Main thread running with thread id -1217071408
3525 [03.09. - 16:14:22] [NET__WORLD] Connected to World: 192.168.0.17:9000

==> eqemu_debug_world.log <==
3451 [03.09. - 16:14:22] [WORLD__CONSOLE] New zoneserver #6 from 192.168.0.17:44937
3451 [03.09. - 16:14:22] [WORLD__ZONE] [6] Zone started with name dynamic_05 by launcher zone
3451 [03.09. - 16:14:22] [WORLD__ZONE] [6] Auto zone port configuration.  Telling zone to use port 7005

==> eqemu_debug_zone.log <==
3525 [03.09. - 16:14:22] [ZONE__WORLD] World indicated port 7005 for this zone.
3525 [03.09. - 16:14:22] [ZONE__INIT] Starting EQ Network server on port 7005
3525 [03.09. - 16:14:22] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1378821264
3525 [03.09. - 16:14:22] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1370428560

Now, I have to admit that log.ini is an empty file.

I see no errors in booting up -- I will post that log next.

ProSkeptic · #8 03-09-2012, 05:41 PM

The only problem this server is having is that when a user dies, the user gets disconnected (eventually) and their client "dies" -- the server seems to kill the zone.

Here is full log (minus command-inits) of start-up to first log in for that single user (using the Titanium client) :

[BEGIN] 3/9/2012 4:24:11 PM

Code:

==> eqemu_debug_3444.log <==
---------------------------------------------
[03.09. - 15:14:05] Starting Log: logs/eqemu_debug_3444.log
[03.09. - 15:14:05] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1216689296
[03.09. - 15:14:05] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1233740944
[03.09. - 15:14:05] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1225348240
[03.09. - 15:14:05] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1250792592
[03.09. - 15:14:05] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1242399888

==> eqemu_debug_3460.log <==
---------------------------------------------
[03.09. - 15:14:15] Starting Log: logs/eqemu_debug_3460.log
[03.09. - 15:14:15] [LAUNCHER__INIT] Loading server configuration..
[03.09. - 15:14:15] [LAUNCHER__INIT] Starting main loop...
[03.09. - 15:14:15] [NET__WORLD] Connected to World: 192.168.0.17:9000
[03.09. - 15:14:15] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1215575184
[03.09. - 15:14:20] [LAUNCHER__WORLD] World told us to start zone dynamic_01.
[03.09. - 15:14:20] [LAUNCHER__WORLD] World told us to start zone dynamic_02.
[03.09. - 15:14:20] [LAUNCHER__WORLD] World told us to start zone dynamic_03.
[03.09. - 15:14:20] [LAUNCHER__WORLD] World told us to start zone dynamic_04.
[03.09. - 15:14:20] [LAUNCHER__WORLD] World told us to start zone dynamic_05.
[03.09. - 15:14:20] [LAUNCHER__ERROR] World told us to start zone dynamic_01, but it is already running.
[03.09. - 15:14:20] [LAUNCHER__ERROR] World told us to start zone dynamic_02, but it is already running.
[03.09. - 15:14:20] [LAUNCHER__ERROR] World told us to start zone dynamic_03, but it is already running.
[03.09. - 15:14:20] [LAUNCHER__ERROR] World told us to start zone dynamic_04, but it is already running.
[03.09. - 15:14:20] [LAUNCHER__ERROR] World told us to start zone dynamic_05, but it is already running.
[03.09. - 15:14:20] [LAUNCHER__STATUS] Starting zone dynamic_01
[03.09. - 15:14:20] [LAUNCHER__STATUS] Zone dynamic_01 has been started.
[03.09. - 15:14:20] [LAUNCHER__STATUS] Waiting 20000 milliseconds before booting the second zone.

==> eqemu_debug_world.log <==
---------------------------------------------
3451 [03.09. - 15:14:10] Starting Log: logs/eqemu_debug_world.log
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1223783568
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1215390864
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading server configuration..
3451 [03.09. - 15:14:10] [WORLD__INIT] Log settings loaded from log.ini
3451 [03.09. - 15:14:10] [WORLD__INIT] CURRENT_WORLD_VERSION:EQEmulator 0.8.0
3451 [03.09. - 15:14:10] [WORLD__INIT] Added loginserver 192.168.0.17:5998
3451 [03.09. - 15:14:10] [WORLD__INIT] Connecting to MySQL...
3451 [03.09. - 15:14:10] [WORLD__INIT] HTTP world service disabled.
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading variables..
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading zones..
3451 [03.09. - 15:14:10] [WORLD__INIT] Clearing groups..
3451 [03.09. - 15:14:10] [WORLD__INIT] Clearing raids..
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading items..
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading guilds..
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1232176272
3451 [03.09. - 15:14:10] [WORLD__INIT] Loaded default rule set 'default'
3451 [03.09. - 15:14:10] [WORLD__INIT] Clearing temporary merchant lists..
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading EQ time of day..
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading launcher list..
3451 [03.09. - 15:14:10] [WORLD__INIT] Reboot zone modes ON
3451 [03.09. - 15:14:10] [WORLD__INIT] Deleted 0 stale player corpses from database
3451 [03.09. - 15:14:10] [WORLD__INIT] Deleted 0 stale player backups from database
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading adventures...
3451 [03.09. - 15:14:10] [WORLD__INIT] Purging expired instances
3451 [03.09. - 15:14:10] [WORLD__INIT] Loading char create info...
3451 [03.09. - 15:14:10] [WORLD__INIT] Zone (TCP) listener started.
3451 [03.09. - 15:14:10] [WORLD__INIT] Client (UDP) listener started.
3451 [03.09. - 15:14:10] [NET__IDENTIFY] Registered patch 6.2
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1343718544
3451 [03.09. - 15:14:10] [NET__IDENTIFY] Registered patch Titanium
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1335325840
3451 [03.09. - 15:14:10] [NET__IDENTIFY] Registered patch SoF
3451 [03.09. - 15:14:10] [NET__IDENTIFY] Registered patch SoD
3451 [03.09. - 15:14:10] [NET__IDENTIFY] Registered patch Underfoot
3451 [03.09. - 15:14:10] [WORLD__LS] Connecting to login server: 192.168.0.17:5998
3451 [03.09. - 15:14:10] [WORLD__LS] Connected to Loginserver: 192.168.0.17:5998
3451 [03.09. - 15:14:10] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1361568912
3451 [03.09. - 15:14:15] [WORLD__ZONE] New TCP connection from 192.168.0.17:44925
3451 [03.09. - 15:14:15] [WORLD__CONSOLE] New launcher from 192.168.0.17:44925
3451 [03.09. - 15:14:15] [WORLD__LAUNCH] Adding pending launcher 1
3451 [03.09. - 15:14:15] [WORLD__LAUNCH] Launcher Identified itself as 'zone'. Loading zone list.
3451 [03.09. - 15:14:15] [WORLD__LAUNCH] Removing pending launcher 1. Adding zone to active list.
3451 [03.09. - 15:14:20] [WORLD__LAUNCH] zone: dynamic_01 reported state STARTED (1 starts)
3451 [03.09. - 15:14:21] [WORLD__ZONE] New TCP connection from 192.168.0.17:44927
3451 [03.09. - 15:14:21] [WORLD__CONSOLE] New zoneserver #1 from 192.168.0.17:44927
3451 [03.09. - 15:14:21] [WORLD__ZONE] [1] Zone started with name dynamic_01 by launcher zone
3451 [03.09. - 15:14:21] [WORLD__ZONE] [1] Auto zone port configuration.  Telling zone to use port 7000

==> eqemu_debug_zone.log <==
---------------------------------------------
3471 [03.09. - 15:14:20] Starting Log: logs/eqemu_debug_zone.log
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading server configuration..
3471 [03.09. - 15:14:20] [ZONE__INIT] Log settings loaded from log.ini
3471 [03.09. - 15:14:20] [ZONE__INIT] Connecting to MySQL...
3471 [03.09. - 15:14:20] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEmulator 0.8.0
3471 [03.09. - 15:14:20] [ZONE__INIT] Log settings loaded from ./log.ini
3471 [03.09. - 15:14:20] [ZONE__INIT] Mapping Incoming Opcodes
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading Variables
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading zone names
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading items
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading npc faction lists
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading loot tables
3471 [03.09. - 15:14:20] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1216279696
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading skill caps
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading spells
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading guilds
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading factions
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading titles
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading AA effects
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading tributes
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading corpse timers
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading commands
3471 [03.09. - 15:14:20] command_init(): - Command 'acceptrules' set to access level 0.
--- SNIPPED
3471 [03.09. - 15:14:20] [ZONE__INIT] 247 commands loaded
3471 [03.09. - 15:14:20] [ZONE__INIT] Loaded default rule set 'default'
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading Tasks
3471 [03.09. - 15:14:20] [ZONE__INIT] Loading quests
3471 [03.09. - 15:14:21] [ZONE__INIT] Entering sleep mode
3471 [03.09. - 15:14:21] [NET__IDENTIFY] Registered patch 6.2
3471 [03.09. - 15:14:21] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1359905936
3471 [03.09. - 15:14:21] [NET__IDENTIFY] Registered patch Titanium
3471 [03.09. - 15:14:21] [NET__IDENTIFY] Registered patch SoF
3471 [03.09. - 15:14:21] [NET__IDENTIFY] Registered patch SoD
3471 [03.09. - 15:14:21] [NET__IDENTIFY] Registered patch Underfoot
3471 [03.09. - 15:14:21] [COMMON__THREADS] Main thread running with thread id -1216272688
3471 [03.09. - 15:14:21] [NET__WORLD] Connected to World: 192.168.0.17:9000
3471 [03.09. - 15:14:21] [ZONE__WORLD] World indicated port 7000 for this zone.
3471 [03.09. - 15:14:21] [ZONE__INIT] Starting EQ Network server on port 7000
3471 [03.09. - 15:14:21] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1378022544
3471 [03.09. - 15:14:21] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1369629840

==> eqemu_quest_zone.log <==
---------------------------------------------
3471 [03.09. - 15:14:20] Starting Log: logs/eqemu_quest_zone.log
3471 [03.09. - 15:14:20] Tying perl output to eqemu logs
3471 [03.09. - 15:14:20] Creating EQEmuIO=HASH(0x97a4c24)
3471 [03.09. - 15:14:20] Creating EQEmuIO=HASH(0x9792030)
3471 [03.09. - 15:14:20] Loading perlemb plugins.
3471 [03.09. - 15:14:21] Loading perl commands...

==> eqemu_world.log <==
---------------------------------------------
3451 [03.09. - 15:14:10] Starting Log: logs/eqemu_world.log
3451 [03.09. - 15:14:10] Using database 'peqdb' at 192.168.0.17:3306
3451 [03.09. - 15:14:10] libEMuShareMem.so loaded

==> eqemu_zone.log <==
---------------------------------------------
3471 [03.09. - 15:14:20] Starting Log: logs/eqemu_zone.log
3471 [03.09. - 15:14:20] Using database 'peqdb' at 192.168.0.17:3306
3471 [03.09. - 15:14:20] libEMuShareMem.so loaded

==> login_1331324045.log <==
[Debug] [03.09.12 - 15:14:05] Logging System Init.
[Debug] [03.09.12 - 15:14:05] Config System Init.
[Debug] [03.09.12 - 15:14:05] MySQL Database Init.
[Debug] [03.09.12 - 15:14:05] Server Manager Initialize.
[Network] [03.09.12 - 15:14:05] ServerManager listening on port 5998
[Debug] [03.09.12 - 15:14:05] Client Manager Initialize.
[Network] [03.09.12 - 15:14:05] ClientManager listening on Titanium stream.
[Network] [03.09.12 - 15:14:05] ClientManager listening on SoD stream.
[Debug] [03.09.12 - 15:14:05] Server Started.
[Network] [03.09.12 - 15:14:10] New world server connection from 192.168.0.17:59155
[World] [03.09.12 - 15:14:10] Server Ship_In_A_Bottle(SMALLEQEMU) successfully logged in.

==> script_login.log <==
[Debug] [03.09.12 - 15:14:05] Logging System Init.
[Debug] [03.09.12 - 15:14:05] Config System Init.
[Debug] [03.09.12 - 15:14:05] MySQL Database Init.
[Debug] [03.09.12 - 15:14:05] Server Manager Initialize.
[Network] [03.09.12 - 15:14:05] ServerManager listening on port 5998
[Debug] [03.09.12 - 15:14:05] Client Manager Initialize.
[Network] [03.09.12 - 15:14:05] ClientManager listening on Titanium stream.
[Debug] Starting Log: logs/eqemu_debug_3444.log

==> script_world.log <==
[Debug] Starting Log: logs/eqemu_debug_world.log
[Debug] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1223783568
[Debug] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1215390864
[Debug] [WORLD__INIT] Loading server configuration..
[Debug] [WORLD__INIT] Log settings loaded from log.ini
[Debug] [WORLD__INIT] CURRENT_WORLD_VERSION:EQEmulator 0.8.0
[Debug] [WORLD__INIT] Added loginserver 192.168.0.17:5998
[Debug] [WORLD__INIT] Connecting to MySQL...
[Status] Starting Log: logs/eqemu_world.log
[Status] Using database 'peqdb' at 192.168.0.17:3306
[Debug] [WORLD__INIT] HTTP world service disabled.
[Debug] [WORLD__INIT] Loading variables..
[Debug] [WORLD__INIT] Loading zones..
[Debug] [WORLD__INIT] Clearing groups..
[Debug] [WORLD__INIT] Clearing raids..
[Debug] [WORLD__INIT] Loading items..
[Status] libEMuShareMem.so loaded

==> script_zones.log <==
[Debug] Starting Log: logs/eqemu_debug_3460.log

==> zone-dynamic_01.log <==
[Debug] Starting Log: logs/eqemu_debug_zone.log
[Debug] [ZONE__INIT] Loading server configuration..
[Debug] [ZONE__INIT] Log settings loaded from log.ini
[Debug] [ZONE__INIT] Connecting to MySQL...
[Status] Starting Log: logs/eqemu_zone.log
[Status] Using database 'peqdb' at 192.168.0.17:3306
[Debug] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEmulator 0.8.0
[Debug] [ZONE__INIT] Log settings loaded from ./log.ini
[Debug] [ZONE__INIT] Mapping Incoming Opcodes
[Debug] [ZONE__INIT] Loading Variables
[Debug] [ZONE__INIT] Loading zone names
[Debug] [ZONE__INIT] Loading items
[Status] libEMuShareMem.so loaded
[Debug] [ZONE__INIT] Loading npc faction lists
[Debug] [ZONE__INIT] Loading loot tables
[Debug] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1216279696
[Debug] [ZONE__INIT] Loading skill caps
[Debug] [ZONE__INIT] Loading spells
[Debug] [ZONE__INIT] Loading guilds
[Debug] [ZONE__INIT] Loading factions
[Debug] [ZONE__INIT] Loading titles
[Debug] [ZONE__INIT] Loading AA effects
[Debug] [ZONE__INIT] Loading tributes
[Debug] [ZONE__INIT] Loading corpse timers
[Debug] [ZONE__INIT] Loading commands
[Debug] command_init(): - Command 'acceptrules' set to access level 0.
--- SNIPPED
[Debug] [ZONE__INIT] 247 commands loaded
[Debug] [ZONE__INIT] Loaded default rule set 'default'
[Debug] [ZONE__INIT] Loading Tasks
[Debug] [ZONE__INIT] Loading quests
[Quest] Starting Log: logs/eqemu_quest_zone.log
[Quest] Tying perl output to eqemu logs
[Quest] Creating EQEmuIO=HASH(0x97a4c24)
[Quest] Creating EQEmuIO=HASH(0x9792030)
[Quest] Loading perlemb plugins.
[Quest] Loading perl commands...

==> eqemu_debug_3460.log <==
[03.09. - 15:14:40] [LAUNCHER__STATUS] Starting zone dynamic_02
[03.09. - 15:14:40] [LAUNCHER__STATUS] Zone dynamic_02 has been started.
[03.09. - 15:14:40] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.

==> eqemu_debug_zone.log <==
---------------------------------------------
3481 [03.09. - 15:14:40] Starting Log: logs/eqemu_debug_zone.log
3481 [03.09. - 15:14:40] [ZONE__INIT] Loading server configuration..
3481 [03.09. - 15:14:40] [ZONE__INIT] Log settings loaded from log.ini
3481 [03.09. - 15:14:40] [ZONE__INIT] Connecting to MySQL...

==> eqemu_debug_world.log <==
3451 [03.09. - 15:14:40] [WORLD__LAUNCH] zone: dynamic_02 reported state STARTED (1 starts)

##################  That happens again, with no error for the next four zones
##################  So I snipped it...

==> eqemu_quest_zone.log <==
---------------------------------------------
3496 [03.09. - 15:14:46] Starting Log: logs/eqemu_quest_zone.log
3496 [03.09. - 15:14:46] Tying perl output to eqemu logs
3496 [03.09. - 15:14:46] Creating EQEmuIO=HASH(0x9f30c24)
3496 [03.09. - 15:14:46] Creating EQEmuIO=HASH(0x9f1e030)
3496 [03.09. - 15:14:46] Loading perlemb plugins.
3496 [03.09. - 15:14:47] Loading perl commands...

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:14:47] [ZONE__INIT] Entering sleep mode
3496 [03.09. - 15:14:47] [NET__IDENTIFY] Registered patch 6.2
3496 [03.09. - 15:14:47] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1360471184
3496 [03.09. - 15:14:47] [NET__IDENTIFY] Registered patch Titanium
3496 [03.09. - 15:14:47] [NET__IDENTIFY] Registered patch SoF
3496 [03.09. - 15:14:47] [NET__IDENTIFY] Registered patch SoD
3496 [03.09. - 15:14:47] [NET__IDENTIFY] Registered patch Underfoot

==> eqemu_debug_world.log <==
3451 [03.09. - 15:14:47] [WORLD__ZONE] New TCP connection from 192.168.0.17:44935
3451 [03.09. - 15:14:47] [WORLD__CONSOLE] New zoneserver #5 from 192.168.0.17:44935

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:14:47] [COMMON__THREADS] Main thread running with thread id -1216837936
3496 [03.09. - 15:14:47] [NET__WORLD] Connected to World: 192.168.0.17:9000

==> eqemu_debug_world.log <==
3451 [03.09. - 15:14:47] [WORLD__ZONE] [5] Zone started with name dynamic_05 by launcher zone
3451 [03.09. - 15:14:47] [WORLD__ZONE] [5] Auto zone port configuration.  Telling zone to use port 7004

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:14:47] [ZONE__WORLD] World indicated port 7004 for this zone.
3496 [03.09. - 15:14:47] [ZONE__INIT] Starting EQ Network server on port 7004
3496 [03.09. - 15:14:47] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1378587792
3496 [03.09. - 15:14:47] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1370195088

==> login_1331324045.log <==
[Network] [03.09.12 - 15:16:44] New Titanium client connection from 192.168.0.16:60452
[Network] [03.09.12 - 15:16:44] Application packet recieved from client (size 14)
[Network] [03.09.12 - 15:16:44] Session ready recieved from client.
[Network] [03.09.12 - 15:16:45] Application packet recieved from client (size 28)
[Network] [03.09.12 - 15:16:45] Login recieved from client.
[Client] [03.09.12 - 15:16:45] User: tim
[Client] [03.09.12 - 15:16:45] Hash: 5ee0edb9e2229c0838f1959779f1949031de0123
[Network] [03.09.12 - 15:16:45] Application packet recieved from client (size 12)
[Network] [03.09.12 - 15:16:45] Server list request recieved from client.
[Network] [03.09.12 - 15:16:57] Application packet recieved from client (size 16)
[Network] [03.09.12 - 15:16:57] Play recieved from client, server number 1 sequence 5.
[Network Trace] [03.09.12 - 15:16:57] User-To-World Response recieved.
[Client] [03.09.12 - 15:16:57] Trying to find client with user id of 2.
[Client] [03.09.12 - 15:16:57] Found client with user id of 2 and account name of tim.
[Client] [03.09.12 - 15:16:57] Found sequence and play of 5 1
[Network Trace] [03.09.12 - 15:16:57] dumping packet of size 20
05 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00  | ................
01 00 00 00                                        | ....
[Network Trace] [03.09.12 - 15:16:57] Sending play response with following data, allowed 1, sequence 5, server number 1, message 101
[Network Trace] [03.09.12 - 15:16:57] dumping packet of size 20
05 00 00 00 00 00 00 00 - 00 00 01 65 00 00 00 00  | ...........e....
01 00 00 00                                        | ....
[Network Trace] [03.09.12 - 15:16:57] Sending play response for tim.
[Network Trace] [03.09.12 - 15:16:57] dumping packet of size 20
05 00 00 00 00 00 00 00 - 00 00 01 65 00 00 00 00  | ...........e....
01 00 00 00                                        | ....
[Network] [03.09.12 - 15:16:57] Client disconnected from the server, removing client.

==> eqemu_debug_world.log <==
3451 [03.09. - 15:16:58] [WORLD__CLIENT] New connection from 192.168.0.16:60453
3451 [03.09. - 15:16:59] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode 0x4dd0 did not match expected 0x2792
3451 [03.09. - 15:16:59] [NET__IDENT_TRACE] 192.168.0.16:60453: Tried patch 6.2_world, and it did not match.
3451 [03.09. - 15:16:59] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode 0x4dd0 did not match expected 0x2ec9
3451 [03.09. - 15:16:59] [NET__IDENT_TRACE] 192.168.0.16:60453: Tried patch 6.2_zone, and it did not match.

==> script_world.log <==
[Debug] [WORLD__INIT] Loading guilds..
[Debug] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1232176272
[Debug] [WORLD__INIT] Loaded default rule set 'default'
[Debug] [WORLD__INIT] Clearing temporary merchant lists..
[Debug] [WORLD__INIT] Loading EQ time of day..
[Debug] [WORLD__INIT] Loading launcher list..
[Debug] [WORLD__INIT] Reboot zone modes ON
[Debug] [WORLD__INIT] Deleted 0 stale player corpses from database
[Debug] [WORLD__INIT] Deleted 0 stale player backups from database
[Debug] [WORLD__INIT] Loading adventures...
[Debug] [WORLD__INIT] Purging expired instances
[Debug] [WORLD__INIT] Loading char create info...
[Debug] [WORLD__INIT] Zone (TCP) listener started.
[Debug] [WORLD__INIT] Client (UDP) listener started.
[Debug] [NET__IDENTIFY] Registered patch 6.2
[Debug] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1343718544
[Debug] [NET__IDENTIFY] Registered patch Titanium
[Debug] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1335325840
[Debug] [NET__IDENTIFY] Registered patch SoF
[Debug] [NET__IDENTIFY] Registered patch SoD
[Debug] [NET__IDENTIFY] Registered patch Underfoot
[Debug] [WORLD__LS] Connecting to login server: 192.168.0.17:5998
[Debug] [WORLD__LS] Connected to Loginserver: 192.168.0.17:5998
[Debug] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1361568912
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44925
[Debug] [WORLD__CONSOLE] New launcher from 192.168.0.17:44925
[Debug] [WORLD__LAUNCH] Adding pending launcher 1
[Debug] [WORLD__LAUNCH] Launcher Identified itself as 'zone'. Loading zone list.
[Debug] [WORLD__LAUNCH] Removing pending launcher 1. Adding zone to active list.
[Debug] [WORLD__LAUNCH] zone: dynamic_01 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44927
[Debug] [WORLD__CONSOLE] New zoneserver #1 from 192.168.0.17:44927
[Debug] [WORLD__ZONE] [1] Zone started with name dynamic_01 by launcher zone
[Debug] [WORLD__ZONE] [1] Auto zone port configuration.  Telling zone to use port 7000
[Debug] [WORLD__LAUNCH] zone: dynamic_02 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44929
[Debug] [WORLD__CONSOLE] New zoneserver #2 from 192.168.0.17:44929
[Debug] [WORLD__ZONE] [2] Zone started with name dynamic_02 by launcher zone
[Debug] [WORLD__ZONE] [2] Auto zone port configuration.  Telling zone to use port 7001
[Debug] [WORLD__LAUNCH] zone: dynamic_03 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44931
[Debug] [WORLD__CONSOLE] New zoneserver #3 from 192.168.0.17:44931
[Debug] [WORLD__ZONE] [3] Zone started with name dynamic_03 by launcher zone
[Debug] [WORLD__ZONE] [3] Auto zone port configuration.  Telling zone to use port 7002
[Debug] [WORLD__LAUNCH] zone: dynamic_04 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44933
[Debug] [WORLD__CONSOLE] New zoneserver #4 from 192.168.0.17:44933
[Debug] [WORLD__ZONE] [4] Zone started with name dynamic_04 by launcher zone
[Debug] [WORLD__ZONE] [4] Auto zone port configuration.  Telling zone to use port 7003
[Debug] [WORLD__LAUNCH] zone: dynamic_05 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 192.168.0.17:44935
[Debug] [WORLD__CONSOLE] New zoneserver #5 from 192.168.0.17:44935
[Debug] [WORLD__ZONE] [5] Zone started with name dynamic_05 by launcher zone
[Debug] [WORLD__ZONE] [5] Auto zone port configuration.  Telling zone to use port 7004
[Debug] [WORLD__CLIENT] New connection from 192.168.0.16:60453
[Debug] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode 0x4dd0 did not match expected 0x2792
[Debug] [NET__IDENT_TRACE] 192.168.0.16:60453: Tried patch 6.2_world, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode 0x4dd0 did not match expected 0x2ec9
[Debug] [NET__IDENT_TRACE] 192.168.0.16:60453: Tried patch 6.2_zone, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode matched 0x4dd0 and length matched 464
==> eqemu_debug_world.log <==
3451 [03.09. - 15:16:59] [NET__IDENT_TRACE] 192.168.0.16:60453: First opcode matched 0x4dd0 and length matched 464
3451 [03.09. - 15:16:59] [NET__IDENTIFY] Identified stream 192.168.0.16:60453 with signature Titanium_world
3451 [03.09. - 15:16:59] [WORLD__CLIENT] Checking inbound connection 192.168.0.16 against BannedIPs table
3451 [03.09. - 15:16:59] [WORLD__CLIENT] Connection 192.168.0.16 PASSED banned IPs check.  Processing connection.
3451 [03.09. - 15:16:59] [WORLD__CLIENT] tim: Logged in. Mode=(CharSel)
3451 [03.09. - 15:16:59] [WORLD__CLIENT] tim: LS Account #2
3451 [03.09. - 15:18:12] [WORLD__CLIENT] tim: Attempting autobootup of tutorialb (189:0)

################# I am entering zone here

==> eqemu_zone.log <==
3496 [03.09. - 15:18:12] Booting tutorialb (189:0)

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:18:12] The next weather check for zone: tutorialb will be in 7748 seconds.

==> eqemu_zone.log <==
3496 [03.09. - 15:18:12] Loading spawn conditions...
3496 [03.09. - 15:18:12] Loading static zone points...
3496 [03.09. - 15:18:12] Loading spawn groups...
3496 [03.09. - 15:18:12] Loading spawn2 points...
3496 [03.09. - 15:18:12] Loading player corpses...
3496 [03.09. - 15:18:12] Loading traps...
3496 [03.09. - 15:18:12] Loading adventure flavor text...
3496 [03.09. - 15:18:12] Loading ground spawns...
3496 [03.09. - 15:18:12] Loading Ground Spawns from DB...
3496 [03.09. - 15:18:12] Loading World Objects from DB...
3496 [03.09. - 15:18:12] Loading Objects from DB...
3496 [03.09. - 15:18:12] Loading doors for tutorialb ...
3496 [03.09. - 15:18:12] Loading Doors from database...
3496 [03.09. - 15:18:12] Loading AA information...
3496 [03.09. - 15:18:12] Loading AA Effects...
3496 [03.09. - 15:18:12] Loaded 389 AA Effects.
3496 [03.09. - 15:18:12] Loading Merchant Lists...
3496 [03.09. - 15:18:12] Loading Temporary Merchant Lists...
3496 [03.09. - 15:18:12] Successfully loaded Zone Config.
3496 [03.09. - 15:18:12] Loading timezone data...
3496 [03.09. - 15:18:12] Init Finished: ZoneID = 189, Time Offset = 0
3496 [03.09. - 15:18:12] Path File Header: Version 2, PathNodes 373
3496 [03.09. - 15:18:12] Path File ./Maps/tutorialb.path loaded.

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:18:12] Zone: tutorialb has weather of type 1.
3496 [03.09. - 15:18:12] Zone: tutorialb(189) has weather type = 1. The weather timer has been enabled.

==> eqemu_zone.log <==
---------------------------------------------
3496 [03.09. - 15:18:12] Starting Log: logs/eqemu_zone.log
3496 [03.09. - 15:18:12] ---- Zone server tutorialb, listening on port:7004 ----
3496 [03.09. - 15:18:12] Zone Bootup: tutorialb (189: 0)

==> eqemu_debug_world.log <==
3451 [03.09. - 15:18:12] [WORLD__ZONE] [5] Setting to 'tutorialb' (189:0)
3451 [03.09. - 15:18:12] [WORLD__CLIENT] tim: Entering zone tutorialb (189:0)
3451 [03.09. - 15:18:12] [WORLD__ZONE] [5] [tutorialb] Broadcasting a world time update
3451 [03.09. - 15:18:12] [WORLD__ZONE] [5] [tutorialb] Setting to 'tutorialb' (189:0)
3451 [03.09. - 15:18:12] [WORLD__CLIENT] tim: Sending client to zone tutorialb (189:0) at 192.168.0.17:7004
3451 [03.09. - 15:18:13] [WORLD__CLIENT] tim: Client disconnected (not active in process)

==> eqemu_debug_zone.log <==
3496 [03.09. - 15:18:13] [WORLD__CLIENT] New connection from 192.168.0.16:55297
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: First opcode 0x7752 did not match expected 0x2792
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: Tried patch 6.2_world, and it did not match.
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: First opcode 0x7213 did not match expected 0x2ec9
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: Tried patch 6.2_zone, and it did not match.
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: First opcode 0x7752 did not match expected 0x4dd0
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: Tried patch Titanium_world, and it did not match.
3496 [03.09. - 15:18:13] [NET__IDENT_TRACE] 192.168.0.16:55297: First opcode matched 0x7213 and length matched 68
3496 [03.09. - 15:18:13] [NET__IDENTIFY] Identified stream 192.168.0.16:55297 with signature Titanium_zone
3496 [03.09. - 15:18:13] [WORLD__CLIENT] New client from 192.168.0.16:55297
3496 [03.09. - 15:18:13] AIYellForHelp("Guard_Hobart000","a_cave_bat001") Guard_Maddoc000 attacking a_cave_bat001 Dist 369.653930 Z 5.379763
3496 [03.09. - 15:18:14] AIYellForHelp("Guard_Maddoc000","a_cave_bat001") Guard_Hobart000 attacking a_cave_bat001 Dist 59.380957 Z 5.943867

Player now sees client as in zone.

**bad_captain** · #9 03-09-2012, 08:25 PM

I'm not familiar with running the server on Linux, but there should be a way to debug the zone to get a call stack from where the crash occurred. That would be the most useful to help figure out where the issue is. I'm sure someone here could help you with that if you don't know how to.

ProSkeptic · #10 03-10-2012, 03:03 AM

Ok, figured it out.

It's the same issue as : http://67.23.190.71/forums/showthread.php?t=30809

And it was also fixed by :

Derision's suggesting :

It appears to be crashing in the assembler in common/crc32.cpp.

Maybe a GCC version related issue (I use 4.1.1 without issue).

A quick thing to try rather than downgrading your GCC version would be to edit common/crc32.cpp and change line 175 from:
Code:
#elif defined(i386)
to
Code:
#elif defined(i386xxx)
and recompiling, so it should fall back to using the C version of the CRC code instead of the assembler.