Problem Logging my Character in: Client Disconnected (not active in process)

Joshua1978 · #1 02-04-2007, 07:07 AM

Hey all.

First just want to say how cool this all is and how excited I am to be putting together my own server. Also, want to let you all know that I spend plenty of time trying to solve problems on my own before buggin someone else with them (I learn better that way) so any issue I post here will be after I've come to the end of my rope.

That being said...

I followed the install procedure (http://www.eqemulator.net/wiki/wikka...=ws70845mysql5) to the letter. I'll note now that I'm using MySQL version 5.0.27 instead of 5.0.24. I don't believe this should cause a problem but you never know...

There were a couple of issues I had to fix after this but I'm now at a point where I can select my server from the list and get into the character creation screen. When I click Enter World, the client pauses (as normal when entering the world) but then I get a black screen that says Server Not Responding, followed by on that says You Have Been Disconnected.

On the world server, I'm getting the following log output:

Code:

[Debug] Starting Log: logs/eqemu_debug_world_1772.log
[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_1772.log
[Status] Using database 'peq' at localhost:3306
[Debug] [WORLD__INIT] Starting HTTP world service...
[Debug] [WORLD__HTTP] Loaded mime types from mime.types
[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] EMuShareMem loaded
[Status] Loading items from database: count=54307
[Debug] [WORLD__INIT] Loading guilds..
[Debug] [WORLD__INIT] Loading EQ time of day..
[Error] Starting Log: logs/eqemu_error_world_1772.log
[Error] Could not load EQTime file eqtime.cfg
[Debug] [WORLD__INIT_ERR] Unable to load eqtime.cfg
[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] Zone (TCP) listener started.
[Debug] [WORLD__INIT] Client (UDP) listener started.
[Debug] [NET__IDENTIFY] Registered patch 6.2
[Debug] [NET__IDENTIFY] Registered patch Titanium
[Debug] [NET__IDENTIFY] Registered patch Live
[Debug] [WORLD__LS] Connecting to login server...
[Debug] [WORLD__LS] Setting World to MiniLogin Server type
[Debug] [WORLD__LS] Connected to Loginserver: 192.168.0.3:5999
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:1103
[Debug] [WORLD__CONSOLE] New launcher from 127.0.0.1:1103
[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__ZONE] New TCP connection from 127.0.0.1:1106
[Debug] [WORLD__CONSOLE] New zoneserver #1 from 127.0.0.1:1106
[Debug] [WORLD__ZONE] [1] Zone started with name dynamic_01 by launcher zones
[Debug] [WORLD__ZONE] [1] Auto zone port configuration.  Telling zone to use por
t 7000
[Debug] [WORLD__LAUNCH] zones: dynamic_02 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:1108
[Debug] [WORLD__CONSOLE] New zoneserver #2 from 127.0.0.1:1108
[Debug] [WORLD__ZONE] [2] Zone started with name dynamic_02 by launcher zones
[Debug] [WORLD__ZONE] [2] Auto zone port configuration.  Telling zone to use por
t 7001
[Debug] [WORLD__LAUNCH] zones: dynamic_03 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:1110
[Debug] [WORLD__CONSOLE] New zoneserver #3 from 127.0.0.1:1110
[Debug] [WORLD__ZONE] [3] Zone started with name dynamic_03 by launcher zones
[Debug] [WORLD__ZONE] [3] Auto zone port configuration.  Telling zone to use por
t 7002
[Debug] [WORLD__LAUNCH] zones: dynamic_04 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:1112
[Debug] [WORLD__CONSOLE] New zoneserver #4 from 127.0.0.1:1112
[Debug] [WORLD__ZONE] [4] Zone started with name dynamic_04 by launcher zones
[Debug] [WORLD__ZONE] [4] Auto zone port configuration.  Telling zone to use por
t 7003
[Debug] [WORLD__LAUNCH] zones: dynamic_05 reported state STARTED (1 starts)
[Debug] [WORLD__ZONE] New TCP connection from 127.0.0.1:1114
[Debug] [WORLD__CONSOLE] New zoneserver #5 from 127.0.0.1:1114
[Debug] [WORLD__ZONE] [5] Zone started with name dynamic_05 by launcher zones
[Debug] [WORLD__ZONE] [5] Auto zone port configuration.  Telling zone to use por
t 7004
[Debug] [WORLD__CLIENT] New connection from 192.168.0.4:1050
[Debug] [NET__IDENT_TRACE] 192.168.0.4:1050: First opcode 0x4dd0 did not match e
xpected 0x2792
[Debug] [NET__IDENT_TRACE] 192.168.0.4:1050: Tried patch 6.2_world, and it did n
ot match.
[Debug] [NET__IDENT_TRACE] 192.168.0.4:1050: First opcode 0x4dd0 did not match e
xpected 0x2ec9
[Debug] [NET__IDENT_TRACE] 192.168.0.4:1050: Tried patch 6.2_zone, and it did no
t match.
[Debug] [NET__IDENT_TRACE] 192.168.0.4:1050: First opcode matched 0x4dd0 and len
gth matched 464
[Debug] [NET__IDENTIFY] Identified stream 192.168.0.4:1050 with signature Titani
um_world
[Debug] [WORLD__CLIENT] New client from 192.168.0.4:1050
[Debug] [WORLD__CLIENT_TRACE] NOCLE: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT] Admin: Logged in. Mode=(CharSel)
[Debug] [WORLD__CLIENT] Admin: MiniLogin Account #1
[Debug] [GUILDS__OUT_PACKETS] Admin: Sending OP_GuildsList of length 96064
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x00fc
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x00fb
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x0106
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x0107
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x0101
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x0100
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x00fd
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x010b
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x010c
[Debug] [WORLD__CLIENT] Admin: Attempting autobootup of neriakc (42)
[Debug] [WORLD__ZONE] [5] Setting to 'neriakc' (42)
[Debug] [WORLD__CLIENT] Admin: Entering zone neriakc (42)
[Debug] [WORLD__ZONE] [5] [neriakc] Setting to 'neriakc' (42)
[Debug] [WORLD__CLIENT] Admin: Sending client to zone neriakc (42) at 192.168.0.
3:7004
[Debug] [WORLD__CLIENT_TRACE] Admin: Sending EQApplicationPacket OpCode 0x010d
[Debug] [WORLD__CLIENT_TRACE] Admin: Recevied EQApplicationPacket
[Debug] [WORLD__CLIENT] Admin: Client disconnected (not active in process)

I'm also noticing that I'm getting a bunch of unknown packets on minilogin:

Code:

LoginServer.ini read.
[Status] Loading opcodes..
Server mode: MiniLogin
Login server listening on port:5999
4547 New Server connection: 192.168.0.3 port: 20484
   0: 30 D5 36 00 01 00 00 00                            | 0.6.....
   0: 08 02 36 00 02 00 00 00                            | ..6.....
   0: 00 00 00 00 4D 6F 6E 20 - 41 70 72 20 31 37 20 32  | ....Mon Apr 17 2
  16: 32 3A 33 37 3A 34 32 20 - 32 30 30 36 00 01 00 00  | 2:37:42 2006....
  32: 00                                                 | .
   0: 00 50 5F 52 03 00 00 00                            | .P_R....
   0: 00 00 00 00 4D 6F 6E 20 - 41 70 72 20 31 37 20 32  | ....Mon Apr 17 2
  16: 32 3A 33 37 3A 34 32 20 - 32 30 30 36 00 02 00 00  | 2:37:42 2006....
  32: 00                                                 | .
   0: 00 00 00 00 04 00 00 00                            | ........
   0: 00 00 00 00 4D 6F 6E 20 - 41 70 72 20 31 37 20 32  | ....Mon Apr 17 2
  16: 32 3A 33 37 3A 34 32 20 - 32 30 30 36 00 03 00 00  | 2:37:42 2006....
  32: 00                                                 | .
   0: 00 0C CB 00 05 00 00 00                            | ........
   0: 00 00 00 00 4D 6F 6E 20 - 41 70 72 20 31 37 20 32  | ....Mon Apr 17 2
  16: 32 3A 33 37 3A 34 32 20 - 32 30 30 36 00 04 00 00  | 2:37:42 2006....
  32: 00                                                 | .
   0: 00 00 00 00 4D 6F 6E 20 - 41 70 72 20 31 37 20 32  | ....Mon Apr 17 2
  16: 32 3A 33 37 3A 34 32 20 - 32 30 30 36 00 05 00 00  | 2:37:42 2006....
  32: 00                                                 | .
53641 New client from ip: 192.168.0.4 port: 1049
Unknown packet:  .4233692 [92.153.64.0:15->0.0.0.0:0]
[OpCode 0x0003 (OP_Unknown) Size=11]
 00000: 00 06 00 00 00 00 00 00 - 00 00 00                | ...........

I'm running on two systems on my personal LAN (both systems meet all the sys requirements).

I've searched for this problem in the forums and haven't had much luck finding my specific problem, although I've found a bunch that look similar and are promising. I'll continue to look through these but if anyone knows the answer I'd really appreciate the help.

Thanks all.

Darkonig · #2 02-04-2007, 08:04 AM

I also use MySQL 5.0.27 and installed from that same wiki setup.
http://www.eqemulator.net/forums/showthread.php?t=22354

Joshua1978 · #3 02-04-2007, 08:42 AM

Quote:

Originally Posted by Darkonig

I also use MySQL 5.0.27 and installed from that same wiki setup.
http://www.eqemulator.net/forums/showthread.php?t=22354

So after that did you get it working or are you now having my problem? The issue in this post and my issue don't seem like the same thing to me.

Thanks.

bufferofnewbies · #4 02-04-2007, 02:07 PM

The 'not active ...' part is typical when people zone in to places.

What it sounds like is you are timing out before you can actually get the zone loaded. You need to increase your timeout delay in your rules set.

Try loading this into your sql:

http://www.eqemulator.net/forums/sho...highlight=rule

and see if it helps. If it still doent allow it, try changing the

Code:

INSERT INTO rule_values
  (ruleset_id, rule_name, rule_value)
VALUES
  (0, "World:ZoneAutobootTimeoutMS", "60000");

INSERT INTO rule_values
  (ruleset_id, rule_name, rule_value)
VALUES
  (0, "World:ClientKeepaliveTimeoutMS", "65000");

to larger numbers.

Joshua1978 · #5 02-04-2007, 02:55 PM

Very cool! I'll give this a shot as soon as I get back in front of my server. Just for my own benefit (so I can learn more) what about the log output indicates a zone timeout to you? I never would have even thought that. I was convinced that there was a compatability issue in the format of my db tables vs. my world server.

Thanks again and I'll let you know if this solves it.

Josh

Joshua1978 · #6 02-04-2007, 04:49 PM

Well, I did as you said (increasing the number to 120000 in both cases) and I'm still getting the same result as stated above. One interesting thing is that it seems never to time out from the world.exe side. I get the "Client disconnected (not active in process)" message literally right after I click Enter World. I'm sure I could give better detail if I was more familiar with the system but for now it seems like it's not even trying to time out; as if I'm being outright booted.

Based on this Default Ruleset I just added, it seems a little clear to me that there are a few steps that the server setup tutorials don't cover. Is there a comprehensive list of things that I need to do after I've completed the setup tutorial - like a list of threads I need to read through and execute?

Again, thank you all for your help. It is most appreciated.

Josh