Bot Group Invite Crash

[steve007]

So ive looked all over the forums and don;t seem to find a solution to this query.
When i invite a spawned Bot into a group, the client crash's.


Strangely, this has only just started happening, ive not made any changes or ammendments, its just started happening randomly.

Has anyone else had this issue or know of a fix? Ive tried adding in all the option Bot sources but nothing, same problem, , spawn, invite, crash...

Any help would be greatly appreciated.

*Appologies, this should have been created under serever.

[lerxst2112]

Look in your logs for errors. If you compiled with debug information, post the stack trace you'll find there.

[steve007]

Thanks for your prompt reply, unfortunatly there doesn;t appear to be anything special in the logs, ive even tried completely redoing the database, downloaded and installed the new database.. wiped everything and tried starting again... unfortunatly, the same issue appears....
I'm not sure if this may have somehting to do with spells.. when i click to scribe a spell, the client also crash's, i can invite a spawned bot bard into a group for perhaps, 2, seconds before crash.. where as , any other caster class is an instant crash.. i have copied the logs below.. see if you can see anything ion there... and as always.. thank you LOADS in advance

Zone Log: OK, no issues
Quest logs: OK, no issues
Zone Log
---------------------------------------------
[08.20. - 22:13:36] Starting Log: logs/eqemu_quest_zone_6164.log
[08.20. - 22:13:36] Tying perl output to eqemu logs
[08.20. - 22:13:36] Creating EQEmuIO=HASH(0x4e6bfc)
[08.20. - 22:13:36] Creating EQEmuIO=HASH(0x4d97ec)
[08.20. - 22:13:36] Loading perlemb plugins.
[08.20. - 22:13:36] Subroutine Cwd::fastcwd redefined at C:/Perl/lib/Cwd.pm line 812.
[08.20. - 22:13:36] Subroutine Cwd::getcwd redefined at C:/Perl/lib/Cwd.pm line 812.
[08.20. - 22:13:36] Subroutine Cwd::abs_path redefined at C:/Perl/lib/Cwd.pm line 812.
[08.20. - 22:15:26] Terminating on signal SIGHUP(1)


World Log

---------------------------------------------
[08.20. - 22:12:56] Starting Log: logs/eqemu_debug_world_3196.log
[08.20. - 22:12:56] [WORLD__INIT] Loading server configuration..
[08.20. - 22:12:56] [WORLD__INIT] Log settings loaded from log.ini
[08.20. - 22:12:56] [WORLD__INIT] CURRENT_VERSION: 1.0.0
[08.20. - 22:12:56] [WORLD__INIT] Added loginserver eqemulator.net:5998
[08.20. - 22:12:56] [WORLD__INIT] Connecting to MySQL...
[08.20. - 22:12:56] [WORLD__INIT] HTTP world service disabled.
[08.20. - 22:12:56] [WORLD__INIT] Loading variables..
[08.20. - 22:12:56] [WORLD__INIT] Loading zones..
[08.20. - 22:12:56] [WORLD__INIT] Clearing groups..
[08.20. - 22:12:56] [WORLD__INIT] Clearing raids..
[08.20. - 22:12:56] [WORLD__INIT] Loading items..
[08.20. - 22:12:57] [WORLD__INIT] Loading guilds..
[08.20. - 22:12:57] [WORLD__INIT] Loaded default rule set 'default'
[08.20. - 22:12:57] [WORLD__INIT] Clearing temporary merchant lists..
[08.20. - 22:12:57] [WORLD__INIT] Loading EQ time of day..
[08.20. - 22:12:57] [WORLD__INIT] Loading launcher list..
[08.20. - 22:12:57] [WORLD__INIT] Reboot zone modes ON
[08.20. - 22:12:57] [WORLD__INIT] Deleted 0 stale player corpses from database
[08.20. - 22:12:57] [WORLD__INIT] Deleted 0 stale player backups from database
[08.20. - 22:12:57] [WORLD__INIT] Loading adventures...
[08.20. - 22:12:57] [WORLD__INIT] Purging expired instances
[08.20. - 22:12:57] [WORLD__INIT] Loading char create info...
[08.20. - 22:12:57] [WORLD__INIT] Zone (TCP) listener started.
[08.20. - 22:12:57] [WORLD__INIT] Client (UDP) listener started.
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch 6.2
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch Titanium
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch SoF
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch SoD
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch Underfoot
[08.20. - 22:12:57] [NET__IDENTIFY] Registered patch RoF
[08.20. - 22:12:57] [WORLD__LS] Connecting to login server: eqemulator.net:5998
[08.20. - 22:12:57] [WORLD__LS] Connected to Loginserver: eqemulator.net:5998
[08.20. - 22:12:57] [WORLD__LS] Loginserver provided 82.16.198.63 as world address
[08.20. - 22:13:05] [WORLD__ZONE] New TCP connection from 127.0.0.1:50591
[08.20. - 22:13:05] [WORLD__ZONE] New TCP connection from 127.0.0.1:50592
[08.20. - 22:13:05] [WORLD__ZONE] New TCP connection from 127.0.0.1:50593
[08.20. - 22:13:05] [WORLD__CONSOLE] New UCS Connection from 127.0.0.1:50593
[08.20. - 22:13:05] [WORLD__CONSOLE] New launcher from 127.0.0.1:50592
[08.20. - 22:13:05] [WORLD__LAUNCH] Adding pending launcher 1
[08.20. - 22:13:05] [WORLD__CONSOLE] New QS Connection from 127.0.0.1:50591
[08.20. - 22:13:05] [WORLD__LAUNCH] Launcher Identified itself as 'zone'. Loading zone list.
[08.20. - 22:13:05] [WORLD__LAUNCH] Removing pending launcher 1. Adding zone to active list.
[08.20. - 22:13:10] [WORLD__LAUNCH] zone: dynamic_01 reported state STARTED (1 starts)
[08.20. - 22:13:11] [WORLD__ZONE] New TCP connection from 127.0.0.1:50595
[08.20. - 22:13:11] [WORLD__CONSOLE] New zoneserver #1 from 127.0.0.1:50595
[08.20. - 22:13:11] [WORLD__ZONE] [1] [] Zone started with name dynamic_01 by launcher zone
[08.20. - 22:13:11] [WORLD__ZONE] [1] [] Auto zone port configuration. Telling zone to use port 7000
[08.20. - 22:13:30] [WORLD__LAUNCH] zone: dynamic_02 reported state STARTED (1 starts)
[08.20. - 22:13:31] [WORLD__ZONE] New TCP connection from 127.0.0.1:50610
[08.20. - 22:13:31] [WORLD__CONSOLE] New zoneserver #2 from 127.0.0.1:50610
[08.20. - 22:13:31] [WORLD__ZONE] [2] [] Zone started with name dynamic_02 by launcher zone
[08.20. - 22:13:31] [WORLD__ZONE] [2] [] Auto zone port configuration. Telling zone to use port 7001
[08.20. - 22:13:32] [WORLD__LAUNCH] zone: dynamic_03 reported state STARTED (1 starts)
[08.20. - 22:13:33] [WORLD__ZONE] New TCP connection from 127.0.0.1:50614
[08.20. - 22:13:33] [WORLD__CONSOLE] New zoneserver #3 from 127.0.0.1:50614
[08.20. - 22:13:33] [WORLD__ZONE] [3] [] Zone started with name dynamic_03 by launcher zone
[08.20. - 22:13:33] [WORLD__ZONE] [3] [] Auto zone port configuration. Telling zone to use port 7002
[08.20. - 22:13:33] [WORLD__CLIENT] New connection from 192.168.0.2:55615
[08.20. - 22:13:33] [NET__IDENT_TRACE] 192.168.0.2:55615: First opcode 0x4dd0 did not match expected 0x2792
[08.20. - 22:13:33] [NET__IDENT_TRACE] 192.168.0.2:55615: Tried patch 6.2_world, and it did not match.
[08.20. - 22:13:33] [NET__IDENT_TRACE] 192.168.0.2:55615: First opcode 0x4dd0 did not match expected 0x2ec9
[08.20. - 22:13:33] [NET__IDENT_TRACE] 192.168.0.2:55615: Tried patch 6.2_zone, and it did not match.
[08.20. - 22:13:33] [NET__IDENT_TRACE] 192.168.0.2:55615: First opcode matched 0x4dd0 and length matched 464
[08.20. - 22:13:33] [NET__IDENTIFY] Identified stream 192.168.0.2:55615 with signature Titanium_world
[08.20. - 22:13:33] [WORLD__CLIENT] Checking inbound connection 192.168.0.2 against BannedIPs table
[08.20. - 22:13:33] [WORLD__CLIENT] Connection 192.168.0.2 PASSED banned IPs check. Processing connection.
[08.20. - 22:13:33] [WORLD__CLIENT] bayley007: Logged in. Mode=(CharSel)
[08.20. - 22:13:33] [WORLD__CLIENT] bayley007: LS Account #271945
[08.20. - 22:13:34] [WORLD__LAUNCH] zone: dynamic_04 reported state STARTED (1 starts)
[08.20. - 22:13:35] [WORLD__ZONE] New TCP connection from 127.0.0.1:50618
[08.20. - 22:13:35] [WORLD__CONSOLE] New zoneserver #4 from 127.0.0.1:50618
[08.20. - 22:13:35] [WORLD__ZONE] [4] [] Zone started with name dynamic_04 by launcher zone
[08.20. - 22:13:35] [WORLD__ZONE] [4] [] Auto zone port configuration. Telling zone to use port 7003
[08.20. - 22:13:36] [WORLD__LAUNCH] zone: dynamic_05 reported state STARTED (1 starts)
[08.20. - 22:13:37] [WORLD__ZONE] New TCP connection from 127.0.0.1:50622
[08.20. - 22:13:37] [WORLD__CONSOLE] New zoneserver #5 from 127.0.0.1:50622
[08.20. - 22:13:37] [WORLD__ZONE] [5] [] Zone started with name dynamic_05 by launcher zone
[08.20. - 22:13:37] [WORLD__ZONE] [5] [] Auto zone port configuration. Telling zone to use port 7004
[08.20. - 22:14:23] [WORLD__CLIENT] bayley007: Attempting autobootup of unrest (63:0)
[08.20. - 22:14:24] [WORLD__ZONE] [5] [] Setting to 'unrest' (63:0)
[08.20. - 22:14:24] [WORLD__CLIENT] bayley007: Entering zone unrest (63:0)
[08.20. - 22:14:24] [WORLD__ZONE] [5] [unrest] Broadcasting a world time update
[08.20. - 22:14:25] [WORLD__ZONE] [5] [unrest] Setting to 'unrest' (63:0)
[08.20. - 22:14:25] [WORLD__CLIENT] bayley007: Sending client to zone unrest (63:0) at 192.168.0.2:7004
[08.20. - 22:14:25] [WORLD__CLIENT] bayley007: Client disconnected (not active in process)
[08.20. - 22:15:27] [WORLD__ZONELIST] Removing zoneserver #5 at :7004
[08.20. - 22:15:27] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
[08.20. - 22:15:27] [WORLD__ZONELIST] Removing zoneserver #4 at :7003
[08.20. - 22:15:27] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
[08.20. - 22:15:27] [WORLD__ZONELIST] Removing zoneserver #3 at :7002
[08.20. - 22:15:27] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
[08.20. - 22:15:27] [WORLD__ZONELIST] Removing zoneserver #2 at :7001
[08.20. - 22:15:27] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
[08.20. - 22:15:27] [WORLD__ZONELIST] Removing zoneserver #1 at :7000
[08.20. - 22:15:27] [WORLD__ZONELIST] Hold Zones mode is ON - rebooting lost zone
[08.20. - 22:15:27] [WORLD__LAUNCH] Removing launcher zone (1)

[steve007]

Can;t see anything in there re: spells or notable issues to the naked eye... anything i;m overlooking here?

how about
Debug zone log

[08.20. - 22:13:36] [ZONE__INIT] 254 commands loaded
[08.20. - 22:13:36] [ZONE__INIT] Loaded default rule set 'default'
[08.20. - 22:13:36] [ZONE__INIT] Loading Tasks
[08.20. - 22:13:36] [ZONE__INIT] Loading quests
[08.20. - 22:13:37] [ZONE__INIT] Entering sleep mode
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch 6.2
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch Titanium
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch SoF
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch SoD
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch Underfoot
[08.20. - 22:13:37] [NET__IDENTIFY] Registered patch RoF
[08.20. - 22:13:37] [NET__WORLD] Connected to World: 127.0.0.1:9000
[08.20. - 22:13:37] [ZONE__WORLD] World indicated port 7004 for this zone.
[08.20. - 22:13:37] [ZONE__INIT] Starting EQ Network server on port 7004
[08.20. - 22:14:23] The next weather check for zone: unrest will be in 3794 seconds.
[08.20. - 22:14:24] Zone: unrest has weather of type 1.
[08.20. - 22:14:24] Zone: unrest(63) has weather type = 1. The weather timer has been enabled.
[08.20. - 22:14:25] [WORLD__CLIENT] New connection from 192.168.0.2:55618
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: First opcode 0x7752 did not match expected 0x2792
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: Tried patch 6.2_world, and it did not match.
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: First opcode 0x7213 did not match expected 0x2ec9
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: Tried patch 6.2_zone, and it did not match.
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: First opcode 0x7752 did not match expected 0x4dd0
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: Tried patch Titanium_world, and it did not match.
[08.20. - 22:14:25] [NET__IDENT_TRACE] 192.168.0.2:55618: First opcode matched 0x7213 and length matched 68
[08.20. - 22:14:25] [NET__IDENTIFY] Identified stream 192.168.0.2:55618 with signature Titanium_zone
[08.20. - 22:14:25] [WORLD__CLIENT] New client from 192.168.0.2:55618
[08.20. - 22:14:25] Character not in a group: Darktouch
[08.20. - 22:14:25] [NET__STRUCTS] Wrong size on outbound OP_CharInventory: Got 0, expected multiple of 8


Error Log

---------------------------------------------
[08.20. - 22:14:24] Starting Log: logs/eqemu_error_zone_6164.log
[08.20. - 22:14:24] Error in Zone::LoadTickItems: SELECT it_itemid, it_chance, it_level, it_qglobal, it_bagslot FROM item_tick (#1146: Table 'peq.item_tick' doesn't exist)

[Burningsoul]

Judging by that error log's last line, I'd wager you didn't source in the item_tick update. Aside from that, I'm not too sure. Try sourcing that update in, and then check for errors again.

[steve007]

hi Guys.. and thanks again for your replys.. unfortunatly, no change... few more logs for your perusal...

Debug zone

08.21. - 19:14:34] [ZONE__INIT] Loaded default rule set 'default'
[08.21. - 19:14:34] [ZONE__INIT] Loading Tasks
[08.21. - 19:14:34] [ZONE__INIT] Loading quests
[08.21. - 19:14:34] [ZONE__INIT] Entering sleep mode
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch 6.2
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch Titanium
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch SoF
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch SoD
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch Underfoot
[08.21. - 19:14:35] [NET__IDENTIFY] Registered patch RoF
[08.21. - 19:14:35] [NET__WORLD] Connected to World: 127.0.0.1:9000
[08.21. - 19:14:35] [ZONE__WORLD] World indicated port 7004 for this zone.
[08.21. - 19:14:35] [ZONE__INIT] Starting EQ Network server on port 7004
[08.21. - 19:16:57] The next weather check for zone: unrest will be in 10996 seconds.
[08.21. - 19:16:58] Zone: unrest has weather of type 1.
[08.21. - 19:16:58] Zone: unrest(63) has weather type = 1. The weather timer has been enabled.
[08.21. - 19:16:59] [WORLD__CLIENT] New connection from 192.168.0.2:52792
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: First opcode 0x7752 did not match expected 0x2792
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: Tried patch 6.2_world, and it did not match.
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: First opcode 0x7213 did not match expected 0x2ec9
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: Tried patch 6.2_zone, and it did not match.
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: First opcode 0x7752 did not match expected 0x4dd0
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: Tried patch Titanium_world, and it did not match.
[08.21. - 19:16:59] [NET__IDENT_TRACE] 192.168.0.2:52792: First opcode matched 0x7213 and length matched 68
[08.21. - 19:16:59] [NET__IDENTIFY] Identified stream 192.168.0.2:52792 with signature Titanium_zone
[08.21. - 19:16:59] [WORLD__CLIENT] New client from 192.168.0.2:52792
[08.21. - 19:16:59] Character not in a group: Darktouch
[08.21. - 19:16:59] [NET__STRUCTS] Wrong size on outbound OP_CharInventory: Got 0, expected multiple of 8

Debug World


[08.21. - 19:14:35] [WORLD__ZONE] [5] [] Auto zone port configuration. Telling zone to use port 7004
[08.21. - 19:16:11] [WORLD__CLIENT] New connection from 192.168.0.2:52789
[08.21. - 19:16:11] [NET__IDENT_TRACE] 192.168.0.2:52789: First opcode 0x4dd0 did not match expected 0x2792
[08.21. - 19:16:11] [NET__IDENT_TRACE] 192.168.0.2:52789: Tried patch 6.2_world, and it did not match.
[08.21. - 19:16:11] [NET__IDENT_TRACE] 192.168.0.2:52789: First opcode 0x4dd0 did not match expected 0x2ec9
[08.21. - 19:16:11] [NET__IDENT_TRACE] 192.168.0.2:52789: Tried patch 6.2_zone, and it did not match.
[08.21. - 19:16:11] [NET__IDENT_TRACE] 192.168.0.2:52789: First opcode matched 0x4dd0 and length matched 464
[08.21. - 19:16:11] [NET__IDENTIFY] Identified stream 192.168.0.2:52789 with signature Titanium_world
[08.21. - 19:16:11] [WORLD__CLIENT] Checking inbound connection 192.168.0.2 against BannedIPs table
[08.21. - 19:16:11] [WORLD__CLIENT] Connection 192.168.0.2 PASSED banned IPs check. Processing connection.
[08.21. - 19:16:11] [WORLD__CLIENT] bayley007: Logged in. Mode=(CharSel)
[08.21. - 19:16:11] [WORLD__CLIENT] bayley007: LS Account #271945
[08.21. - 19:16:57] [WORLD__CLIENT] bayley007: Attempting autobootup of unrest (63:0)
[08.21. - 19:16:58] [WORLD__ZONE] [5] [] Setting to 'unrest' (63:0)
[08.21. - 19:16:58] [WORLD__CLIENT] bayley007: Entering zone unrest (63:0)
[08.21. - 19:16:58] [WORLD__ZONE] [5] [unrest] Broadcasting a world time update
[08.21. - 19:16:58] [WORLD__ZONE] [5] [unrest] Setting to 'unrest' (63:0)
[08.21. - 19:16:58] [WORLD__CLIENT] bayley007: Sending client to zone unrest (63:0) at 192.168.0.2:7004
[08.21. - 19:16:59] [WORLD__CLIENT] bayley007: Client disconnected (not active in process)


Debug Launch


---------------------------------------------
[08.21. - 19:14:03] Starting Log: logs/eqemu_debug_launch_7136.log
[08.21. - 19:14:03] [LAUNCHER__INIT] Loading server configuration..
[08.21. - 19:14:03] [LAUNCHER__INIT] Starting main loop...
[08.21. - 19:14:03] [NET__WORLD] Connected to World: 127.0.0.1:9000
[08.21. - 19:14:08] [LAUNCHER__WORLD] World told us to start zone dynamic_01.
[08.21. - 19:14:08] [LAUNCHER__WORLD] World told us to start zone dynamic_02.
[08.21. - 19:14:08] [LAUNCHER__WORLD] World told us to start zone dynamic_03.
[08.21. - 19:14:08] [LAUNCHER__WORLD] World told us to start zone dynamic_04.
[08.21. - 19:14:08] [LAUNCHER__WORLD] World told us to start zone dynamic_05.
[08.21. - 19:14:08] [LAUNCHER__ERROR] World told us to start zone dynamic_01, but it is already running.
[08.21. - 19:14:08] [LAUNCHER__ERROR] World told us to start zone dynamic_02, but it is already running.
[08.21. - 19:14:08] [LAUNCHER__ERROR] World told us to start zone dynamic_03, but it is already running.
[08.21. - 19:14:08] [LAUNCHER__ERROR] World told us to start zone dynamic_04, but it is already running.
[08.21. - 19:14:08] [LAUNCHER__ERROR] World told us to start zone dynamic_05, but it is already running.
[08.21. - 19:14:08] [LAUNCHER__STATUS] Starting zone dynamic_01
[08.21. - 19:14:08] [LAUNCHER__STATUS] Zone dynamic_01 has been started.
[08.21. - 19:14:08] [LAUNCHER__STATUS] Waiting 20000 milliseconds before booting the second zone.
[08.21. - 19:14:28] [LAUNCHER__STATUS] Starting zone dynamic_02
[08.21. - 19:14:28] [LAUNCHER__STATUS] Zone dynamic_02 has been started.
[08.21. - 19:14:28] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.
[08.21. - 19:14:30] [LAUNCHER__STATUS] Starting zone dynamic_03
[08.21. - 19:14:30] [LAUNCHER__STATUS] Zone dynamic_03 has been started.
[08.21. - 19:14:30] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.
[08.21. - 19:14:32] [LAUNCHER__STATUS] Starting zone dynamic_04
[08.21. - 19:14:32] [LAUNCHER__STATUS] Zone dynamic_04 has been started.
[08.21. - 19:14:32] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.
[08.21. - 19:14:34] [LAUNCHER__STATUS] Starting zone dynamic_05
[08.21. - 19:14:34] [LAUNCHER__STATUS] Zone dynamic_05 has been started.
[08.21. - 19:14:34] [LAUNCHER__STATUS] Waiting 2000 milliseconds before booting the next zone.

Thanks in advance

[steve007]

Looks like it may not just be the bots, when i try and Scribe a spell i get a crah too.. same logs as above

[Burningsoul]

Your logs look fine.. At this point I'd nuke it from orbit, start completely fresh, and make sure you're sourcing all the required updates and such. Been there quite a few times myself.