Anyone else see these issues recently?

ProSkeptic · #1 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 · #2 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.