Error creating character

nosfentora · #1 01-26-2012, 10:04 AM

Here's a fresh log from world and login, trying to create a character. i trimmed out the opcode dump. everything else is there.

World

Code:

---------------------------------------------
[01.26. - 08:48:55] Starting Log: logs/eqemu_debug_world_4136.log
[01.26. - 08:48:55] [WORLD__INIT] Loading server configuration..
[01.26. - 08:48:55] [WORLD__INIT] Log settings loaded from log.ini
[01.26. - 08:48:55] [WORLD__INIT] CURRENT_WORLD_VERSION:EQEmulator 0.8.0
[01.26. - 08:48:55] [WORLD__INIT] Added loginserver 192.168.0.100:5998
[01.26. - 08:48:55] [WORLD__INIT] Connecting to MySQL...
[01.26. - 08:48:55] [WORLD__INIT] HTTP world service disabled.
[01.26. - 08:48:55] [WORLD__INIT] Loading variables..
[01.26. - 08:48:55] [WORLD__INIT] Loading zones..
[01.26. - 08:48:55] [WORLD__INIT] Clearing groups..
[01.26. - 08:48:55] [WORLD__INIT] Clearing raids..
[01.26. - 08:48:55] [WORLD__INIT] Loading items..
[01.26. - 08:49:02] [WORLD__INIT] Loading guilds..
[01.26. - 08:49:02] [WORLD__INIT] Loaded default rule set 'default'
[01.26. - 08:49:02] [WORLD__INIT] Clearing temporary merchant lists..
[01.26. - 08:49:02] [WORLD__INIT] Loading EQ time of day..
[01.26. - 08:49:02] [WORLD__INIT] Loading launcher list..
[01.26. - 08:49:02] [WORLD__INIT] Reboot zone modes ON
[01.26. - 08:49:02] [WORLD__INIT] Deleted 0 stale player corpses from database
[01.26. - 08:49:02] [WORLD__INIT] Deleted 0 stale player backups from database
[01.26. - 08:49:02] [WORLD__INIT] Loading adventures...
[01.26. - 08:49:02] [WORLD__INIT] Purging expired instances
[01.26. - 08:49:02] [WORLD__INIT] Loading char create info...
[01.26. - 08:49:02] [WORLD__INIT] Zone (TCP) listener started.
[01.26. - 08:49:02] [WORLD__INIT] Client (UDP) listener started.
[01.26. - 08:49:02] [NET__IDENTIFY] Registered patch 6.2
[01.26. - 08:49:02] [NET__IDENTIFY] Registered patch Titanium
[01.26. - 08:49:02] [NET__IDENTIFY] Registered patch SoF
[01.26. - 08:49:02] [NET__IDENTIFY] Registered patch SoD
[01.26. - 08:49:02] [NET__IDENTIFY] Registered patch Underfoot
[01.26. - 08:49:02] [WORLD__LS] Connecting to login server: 192.168.0.100:5998
[01.26. - 08:49:02] [WORLD__LS] Connected to Loginserver: 192.168.0.100:5998
[01.26. - 08:49:03] [WORLD__LS] Received ServerOP_LSAccountUpdate packet from loginserver
[01.26. - 08:49:11] [WORLD__ZONE] New TCP connection from 127.0.0.1:49240
[01.26. - 08:49:11] [WORLD__CONSOLE] New launcher from 127.0.0.1:49240
[01.26. - 08:49:11] [WORLD__LAUNCH] Adding pending launcher 1
[01.26. - 08:49:11] [WORLD__LAUNCH] Launcher Identified itself as 'zone'. Loading zone list.
[01.26. - 08:49:11] [WORLD__LAUNCH] Removing pending launcher 1. Adding zone to active list.
[01.26. - 08:49:16] [WORLD__LAUNCH] zone: dynamic_01 reported state STARTED (1 starts)
[01.26. - 08:49:30] [WORLD__ZONE] New TCP connection from 127.0.0.1:49242
[01.26. - 08:49:30] [WORLD__CONSOLE] New zoneserver #1 from 127.0.0.1:49242
[01.26. - 08:49:30] [WORLD__ZONE] [1] Zone started with name dynamic_01 by launcher zone
[01.26. - 08:49:30] [WORLD__ZONE] [1] Auto zone port configuration.  Telling zone to use port 7000
[01.26. - 08:49:31] [WORLD__ZONE] New TCP connection from 127.0.0.1:49244
[01.26. - 08:49:31] [WORLD__CONSOLE] New UCS Connection from 127.0.0.1:49244
[01.26. - 08:49:36] [WORLD__LAUNCH] zone: dynamic_02 reported state STARTED (1 starts)
[01.26. - 08:49:36] [WORLD__ZONE] New TCP connection from 127.0.0.1:49246
[01.26. - 08:49:36] [WORLD__CONSOLE] New zoneserver #2 from 127.0.0.1:49246
[01.26. - 08:49:36] [WORLD__ZONE] [2] Zone started with name dynamic_02 by launcher zone
[01.26. - 08:49:36] [WORLD__ZONE] [2] Auto zone port configuration.  Telling zone to use port 7001
[01.26. - 08:49:38] [WORLD__LAUNCH] zone: dynamic_03 reported state STARTED (1 starts)
[01.26. - 08:49:38] [WORLD__ZONE] New TCP connection from 127.0.0.1:49248
[01.26. - 08:49:38] [WORLD__CONSOLE] New zoneserver #3 from 127.0.0.1:49248
[01.26. - 08:49:38] [WORLD__ZONE] [3] Zone started with name dynamic_03 by launcher zone
[01.26. - 08:49:38] [WORLD__ZONE] [3] Auto zone port configuration.  Telling zone to use port 7002
[01.26. - 08:49:40] [WORLD__LAUNCH] zone: dynamic_04 reported state STARTED (1 starts)
[01.26. - 08:49:40] [WORLD__ZONE] New TCP connection from 127.0.0.1:49252
[01.26. - 08:49:40] [WORLD__CONSOLE] New zoneserver #4 from 127.0.0.1:49252
[01.26. - 08:49:40] [WORLD__ZONE] [4] Zone started with name dynamic_04 by launcher zone
[01.26. - 08:49:40] [WORLD__ZONE] [4] Auto zone port configuration.  Telling zone to use port 7003
[01.26. - 08:49:42] [WORLD__LAUNCH] zone: dynamic_05 reported state STARTED (1 starts)
[01.26. - 08:49:42] [WORLD__ZONE] New TCP connection from 127.0.0.1:49254
[01.26. - 08:49:42] [WORLD__CONSOLE] New zoneserver #5 from 127.0.0.1:49254
[01.26. - 08:49:42] [WORLD__ZONE] [5] Zone started with name dynamic_05 by launcher zone
[01.26. - 08:49:42] [WORLD__ZONE] [5] Auto zone port configuration.  Telling zone to use port 7004
[01.26. - 08:49:52] [WORLD__CLIENT] New connection from 192.168.0.101:49471
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x2792
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch 6.2_world, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x2ec9
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch 6.2_zone, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x4dd0
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch Titanium_world, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x7213
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch Titanium_zone, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x6c3c
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch SoF_world, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x737e
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch SoF_zone, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0xff4
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch SoD_world, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode 0x13da did not match expected 0x5a6b
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: Tried patch SoD_zone, and it did not match.
[01.26. - 08:49:52] [NET__IDENT_TRACE] 192.168.0.101:49471: First opcode matched 0x13da and length matched 464
[01.26. - 08:49:52] [NET__IDENTIFY] Identified stream 192.168.0.101:49471 with signature Underfoot_world
[01.26. - 08:49:52] [WORLD__CLIENT] Checking inbound connection 192.168.0.101 against BannedIPs table
[01.26. - 08:49:52] [WORLD__CLIENT] Connection 192.168.0.101 PASSED banned IPs check.  Processing connection.
[01.26. - 08:49:52] [WORLD__CLIENT] Nosfentora: Logged in. Mode=(CharSel)
[01.26. - 08:49:52] [WORLD__CLIENT] Nosfentora: LS Account #100000
[01.26. - 08:50:02] Unable to convert EQ opcode 0x46d3 to an Application opcode.
[01.26. - 08:50:02] [WORLD__CLIENT_ERR] Nosfentora: Received unknown EQApplicationPacket
[01.26. - 08:50:02] [WORLD__CLIENT_ERR] [OpCode OP_Unknown (0x46d3) Size=2056]
[01.26. - 08:50:02] [WORLD__CLIENT_ERR] 0000: 5b 43 7d e8 00 4a 0a 00 - 50 60 01 00 cb 93 00 00  | [C}..J..P`......
...
[01.26. - 08:50:02] Unable to convert EQ opcode 0x040b to an Application opcode.
[01.26. - 08:50:02] [WORLD__CLIENT_ERR] Nosfentora: Received unknown EQApplicationPacket
[01.26. - 08:50:02] [WORLD__CLIENT_ERR] [OpCode OP_Unknown (0x040b) Size=2056]
...
[01.26. - 08:50:14] Unable to convert EQ opcode 0x184e to an Application opcode.
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] Nosfentora: Received unknown EQApplicationPacket
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] [OpCode OP_Unknown (0x184e) Size=1]
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] 0000: 30                      -                          | 0
[01.26. - 08:50:14] Unable to convert EQ opcode 0x5f80 to an Application opcode.
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] Nosfentora: Received unknown EQApplicationPacket
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] [OpCode OP_Unknown (0x5f80) Size=4]
[01.26. - 08:50:14] [WORLD__CLIENT_ERR] 0000: 08 00 00 00             -                          | ....
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Name approval request.  Name=Cerk, race=Iksar, class=Shadowknight
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Character creation request from Nosfentora LS#100000 (192.168.0.101:49471) : 
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Name: Cerk
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Race: 128  Class: 5  Gender: 1  Deity: 203  Start zone: 10
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: STR  STA  AGI  DEX  WIS  INT  CHA    Total
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora:  80   95   90   85   80   85   60     575
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Face: 1  Eye colors: 5 5
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Hairstyle: 255  Haircolor: 255
[01.26. - 08:52:45] [WORLD__CLIENT] Nosfentora: Beard: 255  Beardcolor: 255
[01.26. - 08:52:45] [WORLD__CLIENT] Validating char creation info...
[01.26. - 08:52:45] [WORLD__CLIENT_ERR] Could not find class/race/deity/start_zone combination
[01.26. - 08:52:45] [WORLD__CLIENT_ERR] Nosfentora: CheckCharCreateInfo did not validate the request (bad race/class/stats)
[01.26. - 08:52:46] [WORLD__CLIENT_ERR] Nosfentora: Could not get CharInfo for 'Cerk'
[01.26. - 08:52:46] [WORLD__CLIENT] Nosfentora: Client disconnected (not active in process)
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Caught signal 2
[01.26. - 08:55:49] [WORLD__SHUTDOWN] World main loop completed.
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Shutting down console connections (if any).
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Shutting down zone connections (if any).
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Zone (TCP) listener stopped.
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Client (UDP) listener stopped.
[01.26. - 08:55:49] [WORLD__SHUTDOWN] Signaling HTTP service to stop...
[01.26. - 08:55:49] [WORLD__HTTP] Requesting that HTTP Service stop.

login

Code:

[Debug] [01.26.12 - 08:48:50] Logging System Init.
[Debug] [01.26.12 - 08:48:50] Config System Init.
[Debug] [01.26.12 - 08:48:50] MySQL Database Init.
[Debug] [01.26.12 - 08:48:50] Encryption Initialize.
[Debug] [01.26.12 - 08:48:50] Encryption Loaded Successfully.
[Debug] [01.26.12 - 08:48:50] Server Manager Initialize.
[Network] [01.26.12 - 08:48:50] ServerManager listening on port 5998
[Debug] [01.26.12 - 08:48:50] Client Manager Initialize.
[Network] [01.26.12 - 08:48:50] ClientManager listening on Titanium stream.
[Network] [01.26.12 - 08:48:50] ClientManager listening on SoD stream.
[Debug] [01.26.12 - 08:48:50] Server Started.
[Network] [01.26.12 - 08:49:02] New world server connection from 192.168.0.100:49239
[World] [01.26.12 - 08:49:02] Server Nosfentora's Realm(Yak) successfully logged in.
[Network Trace] [01.26.12 - 08:49:02] ServerOP_LSAccountUpdate sent to world
[Network] [01.26.12 - 08:49:23] New SoD client connection from 192.168.0.101:58549
[Client] [01.26.12 - 08:49:48] Trying to find client with user id of 100000.
[Client] [01.26.12 - 08:49:48] Found client with user id of 100000 and account name of Nosfentora.
[Client] [01.26.12 - 08:49:48] Found sequence and play of 5 1
[Network Trace] [01.26.12 - 08:49:48] 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] [01.26.12 - 08:49:48] Client disconnected from the server, removing client.
[Network] [01.26.12 - 08:52:50] New SoD client connection from 192.168.0.101:63381
[Network] [01.26.12 - 08:52:50] Session ready indicated logged in from world(unsupported feature), disconnecting.
[Network] [01.26.12 - 08:55:16] New SoD client connection from 192.168.0.101:63382
[Network] [01.26.12 - 08:55:16] Client disconnected from the server, removing client.
[Network] [01.26.12 - 08:55:16] New SoD client connection from 192.168.0.101:63383
[Network] [01.26.12 - 08:55:16] Client disconnected from the server, removing client.
[Network] [01.26.12 - 08:55:16] New SoD client connection from 192.168.0.101:63384
[Network] [01.26.12 - 08:55:16] Client disconnected from the server, removing client.
[Network] [01.26.12 - 08:55:17] New SoD client connection from 192.168.0.101:63385
[Network] [01.26.12 - 08:55:20] Client disconnected from the server, removing client.