Some last errors to get rid of...

Zothen · #1 04-20-2011, 02:48 AM

First of all, my server is running and I am not encountering any issues ingame. But... there are some error messages in my 3 server command windows and in the logs I would like to eliminate.

I know its a big bunch, sorry for that. I appreciate any help.

Btw, whats the proper way to shut down the server? Using CTRL-C so far, but I am not sure if theres a better way...

My config:
Titanium client
Latest Server executables (self-compiled)
Latest PEQ Database
Sourced in all SQL updates from 1711 to 1884 (some errors though)
mysql-5.1.56
ActivePerl-5.10.1.1008

eqemu_debug_world_5820.log

Code:

[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: First opcode 0x4dd0 did not match expected 0x2792
[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: Tried patch 6.2_world, and it did not match.
[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: First opcode 0x4dd0 did not match expected 0x2ec9
[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: Tried patch 6.2_zone, and it did not match.
[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: First opcode matched 0x4dd0 and length matched 464

Code:

[04.19. - 21:09:27] [WORLD__CLIENT_ERR] hoshie1: Received unknown EQApplicationPacket
[04.19. - 21:09:27] [WORLD__CLIENT_ERR] [OpCode OP_WorldLogout (0x7718) Size=0]

eqemu_debug_zone_5932.log

Code:

[04.19. - 21:25:49] [WORLD__CLIENT] New connection from 93.232.157.88:61568
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: First opcode 0x7752 did not match expected 0x2792
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: Tried patch 6.2_world, and it did not match.
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: First opcode 0x7213 did not match expected 0x2ec9
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: Tried patch 6.2_zone, and it did not match.
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: First opcode 0x7752 did not match expected 0x4dd0
[04.19. - 21:25:49] [NET__IDENT_TRACE] 93.232.157.88:61568: Tried patch Titanium_world, and it did not match.

Code:

[04.19. - 21:25:59] [CLIENT__NET_ERR] Mystique: Unhandled incoming opcode: [OpCode OP_WeaponEquip2 (0x63da) Size=8]
[04.19. - 21:25:59] [CLIENT__NET_ERR] Mystique: Unhandled incoming opcode: [OpCode OP_WeaponEquip1 (0x6c5e) Size=12]

Code:

[04.19. - 22:05:20] Member of group 3001 named 'Mystique' had an out of date pointer!!

eqemu_error_zone_5932.log

Code:

[04.19. - 21:25:48] Path File ./Maps/felwitheb.path not found.
[04.19. - 22:05:18] Path File ./Maps/felwithea.path not found.
[04.19. - 22:38:42] Path File ./Maps/gfaydark.path not found.
[04.19. - 22:48:49] Path File ./Maps/gfaydark.path not found.

eqemu_quest_zone_5932.log

Code:

[04.19. - 21:24:46] Useless use of private variable in void context at plugins/formation_tools.pl line 364.

Code:

[04.19. - 21:25:52] Unable to read perl file 'quests/spells/1456.pl'
[04.19. - 21:25:56] Unable to read perl file 'quests/spells/314.pl'
[04.19. - 21:26:01] Unable to read perl file 'quests/spells/488.pl'
[04.19. - 21:26:04] Unable to read perl file 'quests/spells/1743.pl'
[04.19. - 21:26:09] Unable to read perl file 'quests/spells/64.pl'
[04.19. - 21:26:13] Unable to read perl file 'quests/spells/2585.pl'
[04.19. - 22:05:29] Unable to read perl file 'quests/spells/288.pl'
[04.19. - 22:05:29] Unable to read perl file 'quests/spells/26.pl'
[04.19. - 22:06:02] Use of uninitialized value $plugin::name in concatenation (.) or string at plugins/soulbinders.pl line 6.
[04.19. - 22:41:27] Unable to read perl file 'quests/spells/200.pl'
[04.19. - 22:49:36] Unable to read perl file 'quests/spells/239.pl'
[04.19. - 22:49:40] Unable to read perl file 'quests/spells/93.pl'
[04.19. - 23:18:50] Unable to read perl file 'quests/spells/92.pl'
[04.19. - 23:19:15] Unable to read perl file 'quests/spells/253.pl'
[04.19. - 23:19:33] Unable to read perl file 'quests/spells/249.pl'

login_1303241046.log

Code:

[Client] [04.19.11 - 21:24:53] Found client with user id of 3 and account name of hoshie2.
[Client] [04.19.11 - 21:24:53] Found sequence and play of 5 1
[Network Trace] [04.19.11 - 21:24:53] 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] [04.19.11 - 21:24:53] Sending play response with following data, allowed 1, sequence 5, server number 1, message 101
[Network Trace] [04.19.11 - 21:24:53] 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] [04.19.11 - 21:24:53] Sending play response for hoshie2.
[Network Trace] [04.19.11 - 21:24:53] 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                                        | ....

Code:

[Network] [04.19.11 - 23:24:05] Application packet recieved from client (size 12)
[Network Error] [04.19.11 - 23:24:05] Recieved unhandled application packet from the client: [OpCode OP_Unknown (0x0012) Size=10].

**trevius** · #2 04-20-2011, 04:29 AM

Most of those logs are normal and there is nothing you could or should do about them. To correct the one about the formation plugins, you should be able to replace the contents of that file with the new one I posted here:

http://www.eqemulator.org/forums/sho...733#post198733

I think the rest of them are all normal to see accept for the soulbinder one which is probably just a minor issue with the soulbinder plugin.

Zothen · #3 04-20-2011, 05:54 AM

Hmm, what about the missing .path and spell files (.pl) ?

You say the op_code errors are normal? It gives me an uneasy feeling reading a patch file (e.g. 6.2) cannot be applied. Especially if its a file called "titanium_world"... :(

How do you shut down your server instance smoothly?

**trevius** · #4 04-20-2011, 07:07 AM

Quote:

Originally Posted by Zothen

Hmm, what about the missing .path and spell files (.pl) ?

Missing .path files just means you don't have a .path file for that zone in your maps folder. Most zones don't have them unless you make them yourself manually.

The spell file errors are not really errors. They are a bit misleading and probably should be added to some filter so they don't show up in normal perl errors. Basically, all that means is a spell was cast that does not have a spell script in your quests/spells folder associated with it. This will be true for nearly all spells unless you are making custom spell scripts for them.

Quote:

Originally Posted by Zothen

You say the op_code errors are normal? It gives me an uneasy feeling reading a patch file (e.g. 6.2) cannot be applied. Especially if its a file called "titanium_world"... :(

The patch errors is just how the server figures out which client version is running on the client that is connecting. It starts checking if it matches the 6.2 opcodes, and if not, then checks Titanium and so on until it finds the matching client version.

The other opcode errors you saw are for opcodes being sent that we currently don't do anything with on the server side and can be ignored. There are literally hundreds of unknown opcodes that the client uses that we don't have setup on the server end. There isn't anything you can do about that unless you want to identify each one and write handling code for them.

Quote:

Originally Posted by Zothen

How do you shut down your server instance smoothly?

I use "#worldshutdown 300 30" in game to shut the server down with a timer and message to my players. Then, I run a stop script since my server runs on Linux, which then basically just kills the processes. On Windows, you should be able to just close your command prompt windows to shut your server down.

Zothen · #5 04-20-2011, 07:19 AM

Tyvm for the info!

Having a clean Titanium install, how come I get an op_code error for "titanium_patch" ? Thats still the part I dont get.

Secrets · #6 04-20-2011, 07:25 AM

Quote:

Originally Posted by Zothen

Tyvm for the info!

Having a clean Titanium install, how come I get an op_code error for "titanium_patch" ? Thats still the part I dont get.

You get it because it's an opcode that has no purpose and we never mapped it to something that has a purpose, it's not needed for running a server because we don't need it (or we haven't found a use for it)

Quote:

[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: First opcode 0x4dd0 did not match expected 0x2792
[04.19. - 21:08:23] [NET__IDENT_TRACE] 127.0.0.1:63227: Tried patch 6.2_world, and it did not match.

This just means it didn't match the patch it is trying. It checks all of the patches registered to what it is expecting (in this case, it's the enter world opcode) and when it finds it, it stops looping through them. This is normal, and log files aren't always bad things, even if they are filtered under error.

Grebbin · #7 04-22-2011, 09:51 PM

Thanks for clearing up those opcode errors. I was seeing a ton of those and was worried there was something wrong with the way I had set up my server!

Zothen · #8 05-14-2011, 10:43 AM

Its me again

I got some more bugs / strangenesses on my list and I dont want to create new threads for every single one:

1. My SoD client still crashes sometimes when zoning. If that happens theres no loading screen, just the "entering..." message and a busy mouse cursor. I need to kill the process and restart to get to that zone. Buddies of mine got the same error.
Btw, I tried all the CPU affinity fixes. I am playing in Windowed Mode.

2. Sometimes when I enter Highpass Hold, I am warped in some void space in the middle of the map. I can see some of my surroundings, but I cant get out. Theres a message: Large warp detected. It only happens in that zone for me and it happens almost 50% of all tries.

3. After zoning, my char is set always to a fixed startpoint in that zone, even if I left the last zone at its edge. Lets say I am zoning to WC from EC at the northern border, I start in WC on the path again.
Sometimes, even the heading is wrong. Take Highpass Hold for example. After leaving Highpass through the East Karana gorge, you start in East Karana facing Highpass Hold.

4. The command #movechar doesnt work or I am doing something wrong. I thought you can zone other characters directly without to zone yourself and summon them. I get a message "Character has been moved" but nothing happens.

5. Sometimes I get an error message when trying to loot a mobs corpse:
Something like OP_LootRequest failed.

6. Leaving the tutorial through the cave entrance behind the waterfall is bugged. Sometimes its working, sometimes not. I saw an error message about not being experienced to use a portal. Dont remember the exact words.

I appreciate any help on these issues.

**trevius** · #9 05-14-2011, 10:59 AM

Quote:

Originally Posted by Zothen

4. The command #movechar doesnt work or I am doing something wrong. I thought you can zone other characters directly without to zone yourself and summon them. I get a message "Character has been moved" but nothing happens.

The #movechar command only works if the char is completely logged out (exited EQ). If they are in-game or at char select, it will fail to work. It is really only good to move stuck chars after they are logged out.

Quote:

Originally Posted by Zothen

5. Sometimes I get an error message when trying to loot a mobs corpse:
Something like OP_LootRequest failed.

Those are more of a debug type of error than anything. Really, I think it would be OK for them to be removed (or at least commented out) since I think all of the corpse bug issues have been resolved. Secrets has mentioned that before too, so maybe she will remove them sometime on the SVN :P

lerxst2112 · #10 05-14-2011, 11:55 AM

Quote:

Originally Posted by Zothen

Its me again

5. Sometimes I get an error message when trying to loot a mobs corpse:
Something like OP_LootRequest failed.

Whenever I see this it's because I'm trying to loot an empty corpse that hasn't despawned yet. The most common cause for me is clicking really fast to loot multiple corpses. Using /hidecorpse looted fixes that problem.

Zothen · #11 05-15-2011, 07:22 AM

Okay, some log entries from my latest zoning crash/hang: (Its really annoying!)

eqemu_debug_zone.log

Code:

[05.15. - 13:10:23] The next weather check for zone: commons will be in 12782 seconds.
[05.15. - 13:10:23] Zone: commons has weather of type 1.
[05.15. - 13:10:23] Zone: commons(21) has weather type = 1. The weather timer has been enabled.
[05.15. - 13:10:24] [WORLD__CLIENT] New connection from 93.232.163.27:50146
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x2792
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch 6.2_world, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x2ec9
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch 6.2_zone, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x4dd0
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch Titanium_world, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x7213
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch Titanium_zone, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x6c3c
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch SoF_world, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0x737e
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch SoF_zone, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode 0x5a6b did not match expected 0xff4
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: Tried patch SoD_world, and it did not match.
[05.15. - 13:10:24] [NET__IDENT_TRACE] 93.232.163.27:50146: First opcode matched 0x5a6b and length matched 68
[05.15. - 13:10:24] [NET__IDENTIFY] Identified stream 93.232.163.27:50146 with signature SoD_zone
[05.15. - 13:10:24] [WORLD__CLIENT] New client from 93.232.163.27:50146
[05.15. - 13:10:26] Unable to convert EQ opcode 0x4036 to an Application opcode.
[05.15. - 13:10:34] Unable to convert EQ opcode 0x4a20 to an Application opcode.
[05.15. - 13:12:34] AIYellForHelp("Guard_Valon000","a_willowisp013") Guard_Colin000 attacking a_willowisp013 Dist 1850.750244 Z 108.428123
[05.15. - 13:13:56] Client 'Mandragora' was destroyed before reaching the connected state:
[05.15. - 13:13:56] We successfully sent player info and spawns, waiting for client to request new zone.

eqemu_error_zone.log

Code:

[05.15. - 13:10:23] Path File ./Maps/commons.path not found.
[05.15. - 13:10:26] HandlePacket() Opcode error: Unexpected packet during CLIENT_CONNECTING: opcode: OP_Unknown (#0 eq=0x0000), size: 4
[05.15. - 13:10:34] HandlePacket() Opcode error: Unexpected packet during CLIENT_CONNECTING: opcode: OP_Unknown (#0 eq=0x0000), size: 4112

**trevius** · #12 05-15-2011, 10:58 AM

Are you zoning multiple characters at once or something? On SoD, I notice less zoning crashes/hangs than previous clients, and it is very rare for me. The only time I really see hang while zoning like you describe is if world crashes at some point before I zone. A world crash will always cause you to hang on your next zone attempt no matter what client you are on. I dunno if that is the case with your hang issues, but is it possible your world is crashing and zones remaining up?

Zothen · #13 05-15-2011, 11:15 AM

Nay, world is running fine. And it happens even if I am the only one zoning.

Zothen · #14 05-16-2011, 02:20 AM

No idea about the other "bugs" ?

Quote:

Originally Posted by Zothen

2. Sometimes when I enter Highpass Hold, I am warped in some void space in the middle of the map. I can see some of my surroundings, but I cant get out. Theres a message: Large warp detected. It only happens in that zone for me and it happens almost 50% of all tries.

3. After zoning, my char is set always to a fixed startpoint in that zone, even if I left the last zone at its edge. Lets say I am zoning to WC from EC at the northern border, I start in WC on the path again.
Sometimes, even the heading is wrong. Take Highpass Hold for example. After leaving Highpass through the East Karana gorge, you start in East Karana facing Highpass Hold.

**trevius** · #15 05-16-2011, 04:27 AM

Sounds like maybe your safe point or zone in point for highpass might be for the old version and putting you in some part of the zone that is causing you to fall through the world or something. Check your zone_points and zone table for that stuff. You may have to use the client bitwise fields to make it work properly for all clients. The large warp detected might be from you falling or maybe from the auto-port if you fall under the world.

For zoning and getting moved when going between zones like EC to WC, I haven't really messed with that much. I know you can change the axis to something like 999999 in the zone_points table to do something related to that type of zone line. I dunno if zoning to the same spot in the next zone is fully supported though.