Spoke to Quest NPC, got booted.

[John Adams]

Not sure if this is a built-in safety precaution, but I set up a spawn script with a ton of quests (23) on him, but didn't set up any of the Quest side - I am testing Dialog Generator. I hailed (right-clicked) the NPC with 23 Quest if/then's and my client was thrown to character select.

Log:
[quote]18:46:39.961 D Char Load World Time...
18:46:39.966 D Quest Sending quest overhead icons upon entering chunk for character John...
18:46:39.967 D Quest Updating quest overhead icons for chunk.
18:46:39.982 D Quest NPC: High Inquisitor Velman has 17 quests.
18:46:39.983 D Quest Checking for quest id: 3485 on character John -- DOES NOT HAVE QUEST
18:46:39.984 D Quest Character: John does not have quest id: 3485.
18:46:39.985 D Quest Checking for quest id: 3480 on character John -- DOES NOT HAVE QUEST
18:46:39.985 D Quest Character: John does not have quest id: 3480.
18:46:39.986 D Quest Checking for quest id: 3654 on character John -- DOES NOT HAVE QUEST
18:46:39.987 D Quest Character: John does not have quest id: 3654.
18:46:39.987 D Quest Checking for quest id: 3476 on character John -- DOES NOT HAVE QUEST
18:46:39.988 D Quest Character: John does not have quest id: 3476.
18:46:39.989 D Quest Checking for quest id: 4657 on character John -- DOES NOT HAVE QUEST
18:46:39.990 D Quest Character: John does not have quest id: 4657.
18:46:39.990 D Quest Checking for quest id: 3241 on character John -- DOES NOT HAVE QUEST
18:46:39.991 D Quest Character: John does not have quest id: 3241.
18:46:39.992 D Quest Checking quest id: 3241 on character John -- NOT COMPLETE
18:46:39.993 D Quest Determining eligibility: Quest id: 3241 has 0 prereq quests.
18:46:39.993 D Quest Sending eligibility icon 1 for quest id:3241 for character John
18:46:39.994 D Quest Adding overhead icon 1 to NPC: High Inquisitor Velman.
18:46:39.995 D Quest Checking for quest id: 8715 on character John -- DOES NOT HAVE QUEST
18:46:39.996 D Quest Character: John does not have quest id: 8715.
18:46:39.997 D Quest Checking for quest id: 3659 on character John -- DOES NOT HAVE QUEST
18:46:39.997 D Quest Character: John does not have quest id: 3659.
18:46:39.998 D Quest Checking for quest id: 3657 on character John -- DOES NOT HAVE QUEST
18:46:39.999 D Quest Character: John does not have quest id: 3657.
18:46:39.999 D Quest Checking for quest id: 8729 on character John -- DOES NOT HAVE QUEST
18:46:40.000 D Quest Character: John does not have quest id: 8729.
18:46:40.001 D Quest Checking for quest id: 7578 on character John -- DOES NOT HAVE QUEST
18:46:40.001 D Quest Character: John does not have quest id: 7578.
18:46:40.002 D Quest Checking for quest id: 7577 on character John -- DOES NOT HAVE QUEST
18:46:40.003 D Quest Character: John does not have quest id: 7577.
18:46:40.003 D Quest Checking for quest id: 8733 on character John -- DOES NOT HAVE QUEST
18:46:40.004 D Quest Character: John does not have quest id: 8733.
18:46:40.005 D Quest Checking for quest id: 8720 on character John -- DOES NOT HAVE QUEST
18:46:40.005 D Quest Character: John does not have quest id: 8720.
18:46:40.006 D Quest Checking for quest id: 3684 on character John -- DOES NOT HAVE QUEST
18:46:40.007 D Quest Character: John does not have quest id: 3684.
18:46:40.007 D Quest Checking for quest id: 8724 on character John -- DOES NOT HAVE QUEST
18:46:40.008 D Quest Character: John does not have quest id: 8724.
18:46:40.009 D Quest Checking for quest id: 3699 on character John -- DOES NOT HAVE QUEST
18:46:40.010 D Quest Character: John does not have quest id: 3699.
18:46:40.010 D Quest NPC: Guard Antonir has 2 quests.
18:46:40.011 D Quest Checking for quest id: 3222 on character John -- DOES NOT HAVE QUEST
18:46:40.012 D Quest Character: John does not have quest id: 3222.
18:46:40.013 D Quest Checking quest id: 3222 on character John -- NOT COMPLETE
18:46:40.013 D Quest Determining eligibility: Quest id: 3222 has 0 prereq quests.
18:46:40.014 D Quest Sending eligibility icon 1 for quest id:3222 for character John
18:46:40.015 D Quest Adding overhead icon 1 to NPC: Guard Antonir.
18:46:40.016 D Quest Checking for quest id: 3241 on character John -- DOES NOT HAVE QUEST
18:46:40.016 D Quest Character: John does not have quest id: 3241.
18:46:40.017 D Quest Checking quest id: 3241 on character John -- NOT COMPLETE
18:46:40.018 D Quest Determining eligibility: Quest id: 3241 has 0 prereq quests.
18:46:40.018 D Quest Sending eligibility icon 1 for quest id:3241 for character John
18:46:40.019 D Quest Adding overhead icon 1 to NPC: Guard Antonir.
18:46:40.033 D Quest Sending quest clickable items upon entering chunk for character John...
18:46:40.034 D Quest Sending quest clickable icons for chunk.
18:46:54.222 D Command Player 'John' used command '.speed' (2)
18:46:54.223 D Command Player 'John' set .speed to 3000
18:47:43.186 D Quest Checking quest id: 3222 on character John -- NOT COMPLETE
18:47:43.187 D Quest Checking for quest id: 3222 on character John -- DOES NOT HAVE QUEST


18:47:46.974 I Chunk Number of diplomacy options: 0
18:47:46.976 D Quest Checking objectives to see if quest is complete...
18:47:46.977 D Quest Objective 1 is not complete.
18:48:04.363 D Quest Checking quest id: 3485 on character John -- NOT COMPLETE
18:48:04.365 D Quest Checking for quest id: 3485 on character John -- DOES NOT HAVE QUEST
18:48:04.370 W Quest Could not get quest_info about quest id:3485 in luaAddQuestText
18:48:04.373 I Chunk Number of diplomacy options: 0
18:48:04.382 I UDP Client from 127.0.0.1:55456 set to disconnect : Unknown
18:48:04.412 I UDP New client connected from 127.0.0.1:55456
18:48:04.413 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.415 I UDP New client connected from 127.0.0.1:55456
18:48:04.416 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.417 I UDP New client connected from 127.0.0.1:55456
18:48:04.418 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.419 I UDP New client connected from 127.0.0.1:55456
18:48:04.419 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.421 I UDP New client connected from 127.0.0.1:55456
18:48:04.421 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.423 I UDP New client connected from 127.0.0.1:55456
18:48:04.423 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.425 I UDP New client connected from 127.0.0.1:55456
18:48:04.425 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.427 I UDP New client connected from 127.0.0.1:55456
18:48:04.427 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.429 I UDP New client connected from 127.0.0.1:55456
18:48:04.429 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.431 I UDP New client connected from 127.0.0.1:55456
18:48:04.431 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.433 I UDP New client connected from 127.0.0.1:55456
18:48:04.433 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.435 I UDP New client connected from 127.0.0.1:55456
18:48:04.435 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.437 I UDP New client connected from 127.0.0.1:55456
18:48:04.438 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:04.440 I UDP New client connected from 127.0.0.1:55456
18:48:04.440 I UDP Client from 127.0.0.1:55456 set to disconnect : Application
18:48:06.116 D Char Loading 12 characters for account_id: 1
18:48:06.121 D Char Loaded 3 characters for account_id: 1
18:48:06.123 D Net Loading character select appearances for John.
18:48:06.125 D Char Loading Appearance Inventory for character_id John (1)
18:48:06.126 D Char Loaded 0 appearance items for character_id 1
18:48:06.127 W Net Unable to properly load character inventory!
18:48:06.127 D Net Character Appearance inventory loaded.
18:48:06.129 D Net Loading character select appearances for Cleveland Steamroller.
18:48:06.130 D Char Loading Appearance Inventory for character_id Cleveland Steamroller (46)
18:48:06.131 D Char Loaded 0 appearance items for character_id 46
18:48:06.131 W Net Unable to properly load character inventory!
18:48:06.132 D Net Character Appearance inventory loaded.
18:48:06.134 D Net Loading character select appearances for Spell Tester.
18:48:06.135 D Char Loading Appearance Inventory for character_id Spell Tester (84)
18:48:06.135 D Char Loaded 0 appearance items for character_id 84
18:48:06.136 W Net Unable to properly load character inventory!
18:48:06.137 D Net Character Appearance inventory loaded.
18:48:06.305 D Char Loading 12 characters for account_id: 1
18:48:06.309 D Char Loaded 3 characters for account_id: 1
18:48:06.310 D Net Loading character select appearances for John.
18:48:06.312 D Char Loading Appearance Inventory for character_id John (1)
18:48:06.313 D Char Loaded 0 appearance items for character_id 1
18:48:06.313 W Net Unable to properly load character inventory!
18:48:06.314 D Net Character Appearance inventory loaded.
18:48:06.316 D Net Loading character select appearances for Cleveland Steamroller.
18:48:06.316 D Char Loading Appearance Inventory for character_id Cleveland Steamroller (46)
18:48:06.317 D Char Loaded 0 appearance items for character_id 46
18:48:06.318 W Net Unable to properly load character inventory!
18:48:06.319 D Net Character Appearance inventory loaded.
18:48:06.321 D Net Loading character select appearances for Spell Tester.
18:48:06.321 D Char Loading Appearance Inventory for character_id Spell Tester (84)
18:48:06.323 D Char Loaded 0 appearance items for character_id 84
18:48:06.325 W Net Unable to properly load character inventory!
18:48:06.326 D Net Character Appearance inventory loaded.
18:48:06.730 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.731 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.733 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.734 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.734 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.737 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.737 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.739 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.740 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.746 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.747 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.748 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.749 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.750 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.751 I UDP Client from 127.0.0.1:55456 has been removed.
18:48:06.768 I Chunk Starting chunk shutdown timer for Tursh Village.
18:48:36.782 I Chunk Shutdown timer expired. Shutting down Tursh Village.
18:48:36.789 D Chunk Removing chunk_server Tursh Village.
18:48:36.790 D Chunk Chunk_server erased from chunk_servers list.[/quote]

My Lua script is incomplete, but error free. Is booting me from the world an expected result of not having a quest set up? Or is this something else?

[zippyzee]

In the log, shouldn't there be a line indicating that it is calling the 'hailed' function, and the debug line about number of diplomacy options? Those should be there before any script analysis takes place.

[John Adams]

Naturally, I paste 1/2 the log, and what you need is in the other half, right? So there's the whole log, from my login (Load World Time) to when I hailed Guard Antonir and took his quest (3222) and immediately went up the hill to High Inquisitor Velman, hailed him and was at Char Select.

[zippyzee]

I do see 2 'number of diplomacy' options within a half second there. Either the script caused a re-hail to trigger that, or perhaps two right-clicks registered quickly? I've been doing all my testing this week with some quests missing and getting the same warning about not finding quest_info, without crashing.

Did you get a debug assertion? Pretty much any time I drop to character select I find a debug bix up in Visual C++.

This does look like a bug so let me know if you can narrow it down any.

[John Adams]

Those number of diplomacy's are 20s apart. First one was Guard Antonir. Second was the Inquisitor because I had to run up the hill to him.

18:47:46.974      I Chunk Number of diplomacy options: 0
18:48:04.373      I Chunk Number of diplomacy options: 0

No there was no debug errors in C++, the server didn't crash. My character was just dropped to select, and the world was still there for me to log back into.

I'll try my sequence again, and if it does the same thing I'll package up the scripts+data for you to try locally.

[zippyzee]

Ha. I was reading the time codes wrong. Yes, pass on any troublesome combinations for proper testing.

[John Adams]

Good news, you're off the hook. It was, as we say in highly technical terms, a "server burp".

My exact steps retraced, there was no boot this time.

[quote]20:03:21.937 D Quest Getting dialog icon for quest id: 3222, status: 1
20:03:21.938 I Chunk Number of diplomacy options: 0
20:03:21.939 D Quest Checking objectives to see if quest is complete...
20:03:21.941 D Quest Objective 1 is not complete.
20:03:53.717 D Quest Checking quest id: 3485 on character John -- NOT COMPLETE
20:03:53.718 D Quest Checking for quest id: 3485 on character John -- DOES NOT HAVE QUEST
20:03:53.719 W Quest Could not get quest_info about quest id:3485 in luaAddQuestText
20:03:53.720 I Chunk Number of diplomacy options: 0[/quote]

In fact, I suppose I do see this happening to players in other instances unrelated to Questing at all, on New Telon. The logs are just different because I have all spammy logs turned on for Targonor.

Sorry for the false alarm. Now I feel like Moldew... ~walk of shame~

[Moldew]

You still walking, or can this be closed?

[Moldew]

Closed per Xinux.