Problems I ran into and resolutions.

kai4785 · #1 10-12-2007, 11:57 AM

Ok, did it. No change. I think I understand what's supposed to be in the database. You can start static zones that are always running, and you can start dynamic zones so you can travel around with out having to worry about having all zones loaded into memory.

So here's the logs. I'm not sure what's going on. The dynamic zones say they start and report they are running, but I can't load a single zone. Am I missing the files the server needs to load the zones? I have a 'Maps' directory populated with a file for each 'short_name' in the database.

eqemu_debug_world.log shows character creation, and failing to 'boot' the zone.

Code:

31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Face: 1  Eye colors: 0 0
31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Hairstyle: 0  Haircolor: 0
31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Beard: 0  Beardcolor: 0
31861 [10.12. - 17:43:38] [WORLD__CLIENT] Validating char creation info...
31861 [10.12. - 17:43:38] [WORLD__CLIENT] Found 0 errors in character creation request
31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Current location: rivervale  -369.00, -228.00, -17.81
31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Bind location: rivervale  -369.00, -228.00, -17.81
31861 [10.12. - 17:43:38] [WORLD__CLIENT] kai4785: Character creation successful: Perigrine
31861 [10.12. - 17:43:48] [WORLD__CLIENT] kai4785: Attempting autobootup of rivervale (19)
31861 [10.12. - 17:43:48] [WORLD__CLIENT_ERR] kai4785: No zoneserver available to boot up.

zone-dynamic_01.log There are 5 of these, and they are nearly identical:

Code:

[Status] Starting Log: logs/eqemu_zone.log
[Status] Using database 'peq' at localhost:3306
[Debug] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1210815600
[Debug] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEMu 0.7.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

world

Code:

[Debug] Starting Log: logs/eqemu_debug_world.log
[Debug] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1208288368
[Debug] [COMMON__THREADS] Starting TCPServerLoop with thread ID -1218778224
[Debug] [RULES__CHANGE] Resetting running rules to default values
[Debug] [WORLD__INIT] Loading server configuration..
[Debug] [WORLD__INIT] Log settings loaded from log.ini
[Debug] [WORLD__INIT] CURRENT_WORLD_VERSION:EQEMu 0.7.0
[Debug] [WORLD__INIT] Connecting to MySQL...
[Status] Starting Log: logs/eqemu_world.log
[Status] Using database 'peq' at localhost:3306
[Debug] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1229268080
[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 temporary merchant lists..
[Debug] [WORLD__INIT] Loading items..
[Status] libEMuShareMem.so loaded
[Status] Loading items from database: count=54307
[Debug] [WORLD__INIT] Loading guilds..
[Error] Starting Log: logs/eqemu_error_world.log
[Error] Error in LoadRules query SELECT ruleset_id FROM rule_sets WHERE name='default': #1146: Table 'peq.rule_sets' doesn't exist
[Debug] [RULES__ERROR] Failed to find ruleset 'default' for load operation. Canceling.
[Debug] [WORLD__INIT] No rule set configured, using default rules
[Debug] [WORLD__INIT] Loading EQ time of day..
[Debug] [WORLD__INIT] Loading launcher list..
[Debug] [WORLD__INIT] Reboot zone modes OFF
[Debug] [WORLD__INIT] Deleted 0 stale player corpses from database
[Debug] [WORLD__INIT] Deleted 0 stale player backups from database
[Debug] [WORLD__INIT] Zone (TCP) listener started.
[Debug] [WORLD__INIT] Client (UDP) listener started.
[Debug] [COMMON__THREADS] Starting EQStreamFactoryReaderLoop with thread ID -1271661680
[Debug] [COMMON__THREADS] Starting EQStreamFactoryWriterLoop with thread ID -1282151536
[Debug] [NET__IDENTIFY] Registered patch 6.2
[Debug] [NET__IDENTIFY] Registered patch Titanium
[Debug] [NET__IDENTIFY] Registered patch Anniversary
[Debug] [NET__IDENTIFY] Registered patch Live
[Debug] [WORLD__LS] Connecting to login server...
[Debug] [COMMON__THREADS] Starting TCPConnectionLoop with thread ID -1303131248
[Debug] [WORLD__LS] Connected to Loginserver: eqemulator.net:5998
[Debug] [WORLD__LS] Loginserver provided 208.187.198.34 as world address
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:55000
[Debug] [WORLD__CONSOLE] New launcher from 127.0.0.1:55000
[Debug] [WORLD__LAUNCH] Adding pending launcher 1
[Debug] [WORLD__LAUNCH] Launcher Identified itself as 'zones'. Loading zone list.
[Debug] [WORLD__LAUNCH] Removing pending launcher 1. Adding zones to active list.
[Debug] [WORLD__LAUNCH] zones: dynamic_01 reported state STARTED (1 starts)
[Debug] [WORLD__LAUNCH] zones: dynamic_01 reported state STOPPED (1 starts)
[Debug] [WORLD__LAUNCH] zones: dynamic_01 reported state STARTED (2 starts)
[Debug] [WORLD__CLIENT] New connection from 192.168.1.102:1563
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1563: First opcode 0x4dd0 did not match expected 0x2792
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1563: Tried patch 6.2_world, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1563: First opcode 0x4dd0 did not match expected 0x2ec9
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1563: Tried patch 6.2_zone, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1563: First opcode matched 0x4dd0 and length matched 464
[Debug] [NET__IDENTIFY] Identified stream 192.168.1.102:1563 with signature Titanium_world
[Debug] [WORLD__CLIENT] New client from 192.168.1.102:1563
[Debug] [WORLD__CLIENT] kai4785: Logged in. Mode=(CharSel)
[Debug] [WORLD__CLIENT] kai4785: LS Account #95753
[Debug] [WORLD__LAUNCH] zones: dynamic_02 reported state STARTED (1 starts)
[Debug] [WORLD__LAUNCH] zones: dynamic_03 reported state STARTED (1 starts)
[Debug] [WORLD__LAUNCH] zones: dynamic_04 reported state STARTED (1 starts)
[Debug] [WORLD__LAUNCH] zones: dynamic_05 reported state STARTED (1 starts)
[Debug] [WORLD__CLIENT_ERR] kai4785: Received unknown EQApplicationPacket
[Debug] [WORLD__CLIENT_ERR] [OpCode OP_WorldLogout (0x7718) Size=0]
[Debug] [WORLD__CLIENT] kai4785: Client disconnected (not active in process)
[Debug] [WORLD__CLIENT] New connection from 192.168.1.102:1565
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1565: First opcode 0x4dd0 did not match expected 0x2792
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1565: Tried patch 6.2_world, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1565: First opcode 0x4dd0 did not match expected 0x2ec9
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1565: Tried patch 6.2_zone, and it did not match.
[Debug] [NET__IDENT_TRACE] 192.168.1.102:1565: First opcode matched 0x4dd0 and length matched 464
[Debug] [NET__IDENTIFY] Identified stream 192.168.1.102:1565 with signature Titanium_world
[Debug] [WORLD__CLIENT] New client from 192.168.1.102:1565
[Debug] [WORLD__CLIENT] kai4785: Logged in. Mode=(CharSel)
[Debug] [WORLD__CLIENT] kai4785: LS Account #95753
Removing connection
[Debug] [WORLD__CLIENT] kai4785: Name approval request.  Name=Perigrine, race=Halfling, class=Cleric
[Debug] [WORLD__CLIENT] kai4785: Character creation request from kai4785 LS#95753 (192.168.1.102:1565) : 
[Debug] [WORLD__CLIENT] kai4785: Name: Perigrine
[Debug] [WORLD__CLIENT] kai4785: Race: 11  Class: 2  Gender: 0  Deity: 205  Start zone: 3
[Debug] [WORLD__CLIENT] kai4785: STR  STA  AGI  DEX  WIS  INT  CHA    Total
[Debug] [WORLD__CLIENT] kai4785:  75   85   95   90  115   67   50     577
[Debug] [WORLD__CLIENT] kai4785: Face: 1  Eye colors: 0 0
[Debug] [WORLD__CLIENT] kai4785: Hairstyle: 0  Haircolor: 0
[Debug] [WORLD__CLIENT] kai4785: Beard: 0  Beardcolor: 0
[Debug] [WORLD__CLIENT] Validating char creation info...
[Debug] [WORLD__CLIENT] Found 0 errors in character creation request
[Status] Start zone query: SELECT x,y,z,zone_id,bind_id FROM start_zones WHERE player_choice=3 AND player_class=2 AND player_deity=205 AND player_race=11

[Status] Found starting location in start_zones

eqemu_zone.log

Code:

31999 [10.12. - 17:46:49] Starting Log: logs/eqemu_zone.log
31999 [10.12. - 17:46:49] Using database 'peq' at localhost:3306
31999 [10.12. - 17:46:49] libEMuShareMem.so loaded
---------------------------------------------
32003 [10.12. - 17:46:51] Starting Log: logs/eqemu_zone.log
32003 [10.12. - 17:46:51] Using database 'peq' at localhost:3306
32003 [10.12. - 17:46:51] libEMuShareMem.so loaded

eqemu_error_world.log

Code:

---------------------------------------------
31861 [10.12. - 17:40:35] Starting Log: logs/eqemu_error_world.log
31861 [10.12. - 17:40:35] Error in LoadRules query SELECT ruleset_id FROM rule_sets WHERE name='default': #1146: Table 'peq.rule_sets' doesn't exist

eqemu_world.log

Code:

---------------------------------------------
31861 [10.12. - 17:40:32] Starting Log: logs/eqemu_world.log
31861 [10.12. - 17:40:32] Using database 'peq' at localhost:3306
31861 [10.12. - 17:40:32] libEMuShareMem.so loaded
31861 [10.12. - 17:40:32] Loading items from database: count=54307
31861 [10.12. - 17:43:38] Start zone query: SELECT x,y,z,zone_id,bind_id FROM start_zones WHERE player_choice=3 AND player_class=2 AND player_deity=205 AND player_race=11

31861 [10.12. - 17:43:38] Found starting location in start_zones

Angelox · #2 10-12-2007, 12:05 PM

What database are you using , and what version?

kai4785 · #3 10-12-2007, 12:08 PM

$ mysql --version
mysql Ver 14.12 Distrib 5.0.45, for redhat-linux-gnu (i386) using readline 5.0

5.0.45 it looks like.

I think I caught it. It looks like zones are crashing, and when they get restarted, they wipe out their log files. I started the world, let it sit for about 10 minutes, and didn't try to do anything. When I logged into the server, and tried to zone in, I got the following:

Code:

==> eqemu_debug_world.log <==
32336 [10.12. - 18:04:44] [WORLD__CLIENT] kai4785: Attempting autobootup of rivervale (19)
32336 [10.12. - 18:04:44] [WORLD__CLIENT_ERR] kai4785: No zoneserver available to boot up.

==> eqemu_debug_32351.log <==
[10.12. - 18:04:55] [LAUNCHER__STATUS] Zone dynamic_01 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:04:55] [WORLD__LAUNCH] zones: dynamic_01 reported state STOPPED (2 starts)

==> eqemu_debug_zone.log <==
32366 [10.12. - 18:04:55] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> zone-dynamic_01.log <==
[Debug] [ZONE__INIT] Loading npc faction lists
Error: EMuShareMem: DLLLoadNPCFactionLists: !NPCFactionListsMMF.IsLoaded() (timeout)
[Debug] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> eqemu_debug_32351.log <==
[10.12. - 18:05:07] [LAUNCHER__STATUS] Starting zone dynamic_01
[10.12. - 18:05:07] [LAUNCHER__STATUS] Zone dynamic_01 has been started.
[10.12. - 18:05:07] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:07] [WORLD__LAUNCH] zones: dynamic_01 reported state STARTED (3 starts)

==> eqemu_debug_zone.log <==
---------------------------------------------
32492 [10.12. - 18:05:07] Starting Log: logs/eqemu_debug_zone.log
32492 [10.12. - 18:05:07] [RULES__CHANGE] Resetting running rules to default values
32492 [10.12. - 18:05:07] [ZONE__INIT] Loading server configuration..
32492 [10.12. - 18:05:07] [ZONE__INIT] Log settings loaded from log.ini
32492 [10.12. - 18:05:07] [ZONE__INIT] Connecting to MySQL...
32492 [10.12. - 18:05:07] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1211192432
32492 [10.12. - 18:05:07] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEMu 0.7.0
32492 [10.12. - 18:05:07] [ZONE__INIT] Log settings loaded from ./log.ini
32492 [10.12. - 18:05:07] [ZONE__INIT] Mapping Incoming Opcodes
32492 [10.12. - 18:05:07] [ZONE__INIT] Loading Variables
32492 [10.12. - 18:05:07] [ZONE__INIT] Loading zone names
32492 [10.12. - 18:05:07] [ZONE__INIT] Loading items

==> eqemu_zone.log <==
---------------------------------------------
32492 [10.12. - 18:05:07] Starting Log: logs/eqemu_zone.log
32492 [10.12. - 18:05:07] Using database 'peq' at localhost:3306
32492 [10.12. - 18:05:07] libEMuShareMem.so loaded
tail: zone-dynamic_01.log: file truncated

==> eqemu_debug_zone.log <==
32492 [10.12. - 18:05:07] [ZONE__INIT] Loading npc faction lists

==> eqemu_debug_32351.log <==
[10.12. - 18:05:15] [LAUNCHER__STATUS] Zone dynamic_02 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:15] [WORLD__LAUNCH] zones: dynamic_02 reported state STOPPED (1 starts)

==> eqemu_debug_zone.log <==
32376 [10.12. - 18:05:15] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> zone-dynamic_02.log <==
[Debug] [ZONE__INIT] Loading npc faction lists
Error: EMuShareMem: DLLLoadNPCFactionLists: !NPCFactionListsMMF.IsLoaded() (timeout)
[Debug] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> eqemu_debug_32351.log <==
[10.12. - 18:05:17] [LAUNCHER__STATUS] Zone dynamic_03 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:17] [WORLD__LAUNCH] zones: dynamic_03 reported state STOPPED (1 starts)

==> eqemu_debug_zone.log <==
32380 [10.12. - 18:05:17] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> zone-dynamic_03.log <==
[Debug] [ZONE__INIT] Loading npc faction lists
Error: EMuShareMem: DLLLoadNPCFactionLists: !NPCFactionListsMMF.IsLoaded() (timeout)
[Debug] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> eqemu_debug_32351.log <==
[10.12. - 18:05:19] [LAUNCHER__STATUS] Zone dynamic_04 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:19] [WORLD__LAUNCH] zones: dynamic_04 reported state STOPPED (1 starts)

==> eqemu_debug_zone.log <==
32383 [10.12. - 18:05:19] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> zone-dynamic_04.log <==
[Debug] [ZONE__INIT] Loading npc faction lists
Error: EMuShareMem: DLLLoadNPCFactionLists: !NPCFactionListsMMF.IsLoaded() (timeout)
[Debug] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> eqemu_debug_32351.log <==
[10.12. - 18:05:21] [LAUNCHER__STATUS] Zone dynamic_05 has gone down. Restart timer started.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:21] [WORLD__LAUNCH] zones: dynamic_05 reported state STOPPED (1 starts)

==> eqemu_debug_zone.log <==
32387 [10.12. - 18:05:21] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> zone-dynamic_05.log <==
[Debug] [ZONE__INIT] Loading npc faction lists
Error: EMuShareMem: DLLLoadNPCFactionLists: !NPCFactionListsMMF.IsLoaded() (timeout)
[Debug] [ZONE__INIT_ERR] Loading npcs faction lists FAILED!

==> eqemu_debug_32351.log <==
[10.12. - 18:05:27] [LAUNCHER__STATUS] Starting zone dynamic_02
[10.12. - 18:05:27] [LAUNCHER__STATUS] Zone dynamic_02 has been started.
[10.12. - 18:05:27] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.

==> eqemu_debug_world.log <==
32336 [10.12. - 18:05:27] [WORLD__LAUNCH] zones: dynamic_02 reported state STARTED (2 starts)

==> eqemu_debug_zone.log <==
---------------------------------------------
32503 [10.12. - 18:05:27] Starting Log: logs/eqemu_debug_zone.log
32503 [10.12. - 18:05:27] [RULES__CHANGE] Resetting running rules to default values
32503 [10.12. - 18:05:27] [ZONE__INIT] Loading server configuration..
32503 [10.12. - 18:05:27] [ZONE__INIT] Log settings loaded from log.ini
32503 [10.12. - 18:05:27] [ZONE__INIT] Connecting to MySQL...
32503 [10.12. - 18:05:27] [COMMON__THREADS] Starting DBAsyncLoop with thread ID -1211274352
32503 [10.12. - 18:05:27] [ZONE__INIT] CURRENT_ZONE_VERSION: EQEMu 0.7.0
32503 [10.12. - 18:05:27] [ZONE__INIT] Log settings loaded from ./log.ini
32503 [10.12. - 18:05:27] [ZONE__INIT] Mapping Incoming Opcodes
32503 [10.12. - 18:05:27] [ZONE__INIT] Loading Variables
32503 [10.12. - 18:05:27] [ZONE__INIT] Loading zone names
32503 [10.12. - 18:05:27] [ZONE__INIT] Loading items

==> eqemu_zone.log <==
---------------------------------------------
32503 [10.12. - 18:05:27] Starting Log: logs/eqemu_zone.log
32503 [10.12. - 18:05:27] Using database 'peq' at localhost:3306
32503 [10.12. - 18:05:27] libEMuShareMem.so loaded

Angelox · #4 10-12-2007, 12:12 PM

I ment what database as in PEQ (and version or where you got it), AX_CLASSIC, EQ

kai4785 · #5 10-12-2007, 12:14 PM

peq-luclin-release
From http://projecteq.net/downloads.php
I've also tried the peq-ykesha-beta1 database. both are behaving identically.

Angelox · #6 10-12-2007, 12:46 PM

Use the newest, I think is peq-ykesha-beta1 database then get the newest source, and read the logs posted there, there's still at least one update you have to make, I think;

Required SQL:

Code:

alter table npc_faction add column ignore_primary_assist tinyint(3) not null default 0;

And don't forget to use the newest opcodes

kai4785 · #7 10-12-2007, 01:02 PM

Where did you find the updates? I don't see where you want me to go get new opcodes either...

I got further though. It doesn't pop the error right away, and it looks like my client is busy loading, but logs show:

Code:

[Status] Booting rivervale
Map header: 21658 faces, 217 nodes, 29875 facelists
Loaded map: 64974 vertices, 21658 faces
Map BB: (-673.72 -> 203.91, -431.81 -> 511.78, -47.97 -> 599.75)
Map ./Maps/rivervale.map loaded.
Path File ./Maps/rivervale.path not found.
[Debug] The next weather check for zone: rivervale will be in 5660 seconds.
Error loading NPCs from database. Bad query: #1054: Unknown column 'npc_types.trackable' in 'field list'
[Status] Loading spawn conditions...
[Status] Loading static zone points...
[Status] Loading spawn groups...
[Status] Loading spawn2 points...
[Status] Loading player corpses...
[Status] Loading traps...
[Status] Loading ground spawns...
[Status] Loading Ground Spawns from DB...
[Status] Loading World Objects from DB...
[Status] Loading Objects from DB...
[Status] Loading doors for rivervale ...
[Status] Loading Doors from database...
[Status] Loading AA information...
[Error] Error in GetAASkillVars 'SELECT cost, max_level, hotkey_sid, hotkey_sid2, title_sid, desc_sid, type, prereq_skill, prereq_minpoints, spell_type, spell_refresh, classes, berserker,spellid,class_type,name,cost_inc FROM altadv_vars WHERE skill_id=2': #1054: Unknown column 'cost_inc' in 'field list'
tail: zone-dynamic_05.log: file truncated

Then it spits the same error to my client again.

kai4785 · #8 10-12-2007, 01:21 PM

http://projecteq.net/phpBB2/viewtopi...9ca262804e9b8d

This is exactly my problem here as well.

Do you have a link for me to download your Database Angelox? I tried looking, but couldn't find the link.