Java Version: 1.8.0_192
Up-time: 1d 13h
CraftBukkit Version: git-Paper-521 (MC: 1.13.2)
Denizen Version: Core: 1.30 (Build 200), CraftBukkit: 1.0.3-SNAPSHOT (build 616-DEV)
Active Plugins (30): dynmap: 3.0-beta-2-138, WorldBorder: 1.9.0, Votifier: 2.5.0, ProtocolLib: 4.4.0,
Multiverse-Core: 2.7.0-SNAPSHOT-b747, LibsDisguises: 9.7.1, MythicDrops: 4.3.4-a8b9390,
FastAsyncWorldEdit: 1.0, PermissionsEx: 1.23.4, EffectLib: 6.2-SNAPSHOT, Vault: 1.7.1-b91,
Essentials: 2.16.0.16, BKCommonLib: 1.13.2-v2, LightCleaner: 1.13.1-v1, HolographicDisplays: 2.3.2,
Citizens: 2.0.24-SNAPSHOT (build 1615), EssentialsProtect: 2.16.0.16, EssentialsChat: 2.16.0.16,
EssentialsGeoIP: 2.16.0.16, EssentialsAntiBuild: 2.16.0.16, EssentialsSpawn: 2.16.0.16,
EnjinMinecraftPlugin: 3.5.4, Denizen: 1.0.3-SNAPSHOT (build 616-DEV), WorldEdit: unspecified,
WorldEditSelectionVisualizer: 1.6.2, FastAsyncVoxelSniper: 1.13.132, WorldGuard: 7.0.0-SNAPSHOT;0dc5781,
Depenizen: 1.0.0 (build 433), Heroes: 1.9.1-SNAPSHOT-565633b, MythicMobs: 4.6.0-SNAPSHOT-2649,
Loaded Worlds (7): map, map_nether, map_the_end, Temp, map_rainbow, map_dungeons, map_spawn
Online Players (1): [Deity] ~Jimmy(JimyTheLightfoot)
Offline Players: 16
Mode: online
22:23:09 [INFO] +> Executing 'Event': Type='on player clicks CHARACTERSHEETIT in inventory' Container='CHARACTERSHEETHANDLER(WORLD)'
player=p@JimyTheLightfootContext='{item=i@player_head[display_name=Character Sheet;lore=Global Reputation&co 0|Unsung
Reputation&co 1|Cash On Hand&co $11216|Click here to see your Character Information and
Stats&exc;script=CHARACTERSHEETIT;skull_skin=5fb9aeaa-52fd-494a-9388-1f5baab9ed7b|eyJ0aW1lc3RhbXAiOjE1NDk4MTkxMjg2MjgsInByb2ZpbGVJZCI6IjVmYjlhZWFhNTJmZDQ5NGE5Mzg4MWY1YmFhYjllZDdiIiwicHJvZmlsZU5hbWUiOiJKaW15VGhlTGlnaHRmb290IiwidGV4dHVyZXMiOnsiU0tJTiI6eyJ1cmwiOiJodHRwOi8vdGV4dHVyZXMubWluZWNyYWZ0Lm5ldC90ZXh0dXJlLzUxODQ4NmMxNTJiYjA1M2UyMzEyMGExMmNhZTJkNzkwNDVmYjAwOGM2OWQ0NzljMzY1NWFjZTYyYTM2ZDA5NmQifX19|JimyTheLightfoot],
cursor_item=i@air, raw_slot=36, is_shift_click=false, slot_type=CONTAINER, action=PICKUP_ALL, slot=36,
inventory=in@player[holder=p@5fb9aeaa-52fd-494a-9388-1f5baab9ed7b], click=LEFT, hotbar_button=0}'
22:23:09 [INFO] +- Building event 'ON PLAYER CLICKS CHARACTERSHEETIT IN INVENTORY' for CHARACTERSHEETHANDLER ---------+
22:23:09 [INFO] Starting InstantQueue 'CHARACTERSHEETHANDLER_ForgotChairsNbc'...
22:23:09 [INFO] Queue 'CHARACTERSHEETHANDLER_ForgotChairsNbc' Executing: DETERMINE "passively" "cancelled"
22:23:09 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:09 [INFO] +> Executing 'DETERMINE': outcome='cancelled' passively='true'
22:23:09 [INFO] Queue 'CHARACTERSHEETHANDLER_ForgotChairsNbc' Executing: INVENTORY "open" "d:in@CharacterSheetInv"
22:23:09 [INFO] +- Executing dCommand: INVENTORY/p@JimyTheLightfoot ---------+
22:23:09 [INFO] Filled tag <&0> with ''.
22:23:09 [INFO] Filled tag <&0> with ''.
22:23:09 [INFO] Filled tag <&co> with ':'.
22:23:09 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:09 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Total]> with '2'.
22:23:09 [INFO] Filled tag <&co> with ':'.
22:23:09 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:09 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[CompletedQuests.Total]> with '0'.
22:23:09 [INFO] Filled tag <player.heroes.primary_class.name> with 'Warrior'.
22:23:09 [INFO] Filled tag <player.heroes.secondary_class.name> with 'Lumberjack'.
22:23:09 [INFO] Filled tag <&0> with ''.
22:23:09 [INFO] Filled tag <&0> with ''.
22:23:09 [INFO] +> Executing 'INVENTORY': actions='[open]' Inventory='in@CHARACTERSHEETINV' slot='1'
22:23:09 [INFO] Completing queue 'CHARACTERSHEETHANDLER_ForgotChairsNbc' in 14ms.
22:23:10 [INFO] +> Executing 'Event': Type='on player clicks QUESTLOGI in inventory' Container='QUESTLOGWORLD(WORLD)'
player=p@JimyTheLightfootContext='{item=i@writable_book[display_name=Quest Journal;lore=Click here to access information|about
your Active quests&dot|Active Quests&co 2|Completed Quests&co 0;script=QUESTLOGI], cursor_item=i@air, raw_slot=5,
is_shift_click=false, slot_type=CONTAINER, action=PICKUP_ALL, slot=5, inventory=in@CHARACTERSHEETINV, click=LEFT,
hotbar_button=0}'
22:23:10 [INFO] +- Building event 'ON PLAYER CLICKS QUESTLOGI IN INVENTORY' for QUESTLOGWORLD ---------+
22:23:10 [INFO] Starting InstantQueue 'QUESTLOGWORLD_JackLitigationPull'...
22:23:10 [INFO] Queue 'QUESTLOGWORLD_JackLitigationPull' Executing: DETERMINE "passively" "cancelled"
22:23:10 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'DETERMINE': outcome='cancelled' passively='true'
22:23:10 [INFO] Queue 'QUESTLOGWORLD_JackLitigationPull' Executing: INVENTORY "open" "d:in@questjournalmenu"
22:23:10 [INFO] +- Executing dCommand: INVENTORY/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&co> with ':'.
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Total]> with '2'.
22:23:10 [INFO] Filled tag <&co> with ':'.
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[CompletedQuests.Total]> with '0'.
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Applying property 'skull_skin' on object of type 'Item'...
22:23:10 [INFO] Filled tag <&co> with ':'.
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[GlobalReputation]> with '0'.
22:23:10 [INFO] Filled tag <&co> with ':'.
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[UnsungReputation]> with '1'.
22:23:10 [INFO] Filled tag <&co> with ':'.
22:23:10 [INFO] Filled tag <player.money> with '11216'.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Starting InstantQueue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors'...
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:!"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='DELETE(false)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: DEFINE "Total" "45"
22:23:10 [INFO] +- Executing dCommand: DEFINE/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'DEFINE': queue='INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' definition='total' value='45'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: DEFINE "Total"
"<def[total].sub[<yaml[<player.uuid>].read[ActiveQuests.Total]>]>"
22:23:10 [INFO] +- Executing dCommand: DEFINE/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Total]> with '2'.
22:23:10 [INFO] Filled tag <def[total].sub[2]> with '43'.
22:23:10 [INFO] +> Executing 'DEFINE': queue='INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' definition='total' value='43'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player"
"QJTotal:<yaml[<player.uuid>].read[ActiveQuests.Total]>"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Total]> with '2'.
22:23:10 [INFO] +> Executing 'FLAG': flag_name='QJTOTAL' Action/Value='SET_VALUE(2)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FOREACH
"<yaml[<player.uuid>].list_keys[ActiveQuests].exclude[Total]>"
22:23:10 [INFO] +- Executing dCommand: FOREACH/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].list_keys[ActiveQuests].exclude[Total]> with
'li@Test|AncientCatacombs'.
22:23:10 [INFO] +> Executing 'FOREACH': list='li@Test|AncientCatacombs'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player"
"ActiveQuests:|:<yaml[<player.uuid>].read[ActiveQuests.<def[value]>.Item]>"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <def[value]> with 'Test'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Test.Item]> with 'i@writable_book[display_name=Test
Quest;lore=Testing the quests;nbt=li@quest/Test;script=QUESTBOOKIT]'.
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@writable_book[display_name=Test Quest;lore=Testing the
quests;nbt=li@quest/Test;script=QUESTBOOKIT])' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "QJTotal:-:1"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='QJTOTAL' Action/Value='DECREASE(1)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: IF "<player.flag[QJTotal]>" "<=" "0"
22:23:10 [INFO] +- Executing dCommand: IF/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'IF': use_braces='true'
22:23:10 [INFO] Filled tag <player.flag[QJTotal]> with '1'.
22:23:10 [INFO] Comparable='Decimal(1.0)', Operator='OR_LESS', ComparedTo='Decimal(0.0)' --> OUTCOME='false'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FOREACH " CALLBACK"
22:23:10 [INFO] +- Foreach loop 2 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player"
"ActiveQuests:|:<yaml[<player.uuid>].read[ActiveQuests.<def[value]>.Item]>"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:10 [INFO] Filled tag <def[value]> with 'AncientCatacombs'.
22:23:10 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.AncientCatacombs.Item]> with
'i@writable_book[display_name=The Ancient Catacombs;lore=A strange and ominous catacomb|located under Frontier&sqs
Folly&dot|It&sqs just begging to be explored˙nbt=li@quest/AncientCatacombs;script=QUESTBOOKIT]'.
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@writable_book[display_name=The Ancient Catacombs;lore=A
strange and ominous catacomb|located under Frontier&sqs Folly&dot|It&sqs just begging to be
explored˙nbt=li@quest/AncientCatacombs;script=QUESTBOOKIT])' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "QJTotal:-:1"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='QJTOTAL' Action/Value='DECREASE(1)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: IF "<player.flag[QJTotal]>" "<=" "0"
22:23:10 [INFO] +- Executing dCommand: IF/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'IF': use_braces='true'
22:23:10 [INFO] Filled tag <player.flag[QJTotal]> with '0'.
22:23:10 [INFO] Comparable='Decimal(0.0)', Operator='OR_LESS', ComparedTo='Decimal(0.0)' --> OUTCOME='true'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT "<def[Total]>"
22:23:10 [INFO] +- Executing dCommand: REPEAT/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <def[Total]> with '43'.
22:23:10 [INFO] +> Executing 'REPEAT': qty='43'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 2 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 3 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 4 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 5 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 6 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 7 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 8 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 9 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 10 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 11 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 12 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 13 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 14 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 15 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 16 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 17 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 18 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 19 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 20 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 21 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 22 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 23 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 24 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 25 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 26 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 27 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 28 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 29 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 30 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 31 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 32 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 33 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 34 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 35 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 36 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 37 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 38 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 39 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 40 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 41 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 42 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop 43 ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:10 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:10 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: REPEAT " CALLBACK"
22:23:10 [INFO] +- Repeat loop complete ---------+
22:23:10 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' Executing: DETERMINE "<player.flag[ActiveQuests]>"
22:23:10 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:10 [INFO] Filled tag <player.flag[ActiveQuests]> with 'i@writable_book[display_name=Test Quest;lore=Testing the
quests;nbt=li@quest/Test;script=QUESTBOOKIT]|i@writable_book[display_name=The Ancient Catacombs;lore=A strange and ominous
catacomb|located under Frontier&sqs Folly&dot|It&sqs just begging to be
explored˙nbt=li@quest/AncientCatacombs;script=QUESTBOOKIT]|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt...
* snip! *
22:23:10 [INFO] +> Executing 'DETERMINE': outcome='i@writable_book[display_name=Test Quest;lore=Testing the
quests;nbt=li@quest/Test;script=QUESTBOOKIT]|i@writable_book[display_name=The Ancient Catacombs;lore=A strange and ominous
catacomb|located under Frontier&sqs Folly&dot|It&sqs just begging to be
explored˙nbt=li@quest/AncientCatacombs;script=QUESTBOOKIT]|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt|i@PlaceHolderIt'
pas... * snip! *
22:23:10 [INFO] Completing queue 'INV_SCRIPT_ITEM_PROC_AnaheimClintonErrors' in 18ms.
22:23:10 [INFO] Applying property 'display_name' on object of type 'Item'...
22:23:10 [INFO] Applying property 'lore' on object of type 'Item'...
22:23:10 [INFO] Applying property 'nbt' on object of type 'Item'...
22:23:10 [INFO] Applying property 'script' on object of type 'Item'...
22:23:10 [INFO] Applying property 'display_name' on object of type 'Item'...
22:23:10 [INFO] Applying property 'lore' on object of type 'Item'...
22:23:10 [INFO] Applying property 'nbt' on object of type 'Item'...
22:23:10 [INFO] Applying property 'script' on object of type 'Item'...
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] Filled tag <&0> with ''.
22:23:10 [INFO] +> Executing 'INVENTORY': actions='[open]' Inventory='in@QUESTJOURNALMENU' slot='1'
22:23:10 [INFO] Completing queue 'QUESTLOGWORLD_JackLitigationPull' in 31ms.
22:23:11 [INFO] +> Executing 'Event': Type='on player clicks in QUESTJOURNALMENU' Container='QUESTLOGWORLD(WORLD)'
player=p@JimyTheLightfootContext='{item=i@nether_star[display_name=Key Items;lore=Click here to view|Quest related
items˙script=KEYITEMSIT], cursor_item=i@air, raw_slot=49, is_shift_click=false, slot_type=CONTAINER, action=PICKUP_ALL,
slot=49, inventory=in@QUESTJOURNALMENU, click=LEFT, hotbar_button=0}'
22:23:11 [INFO] +- Building event 'ON PLAYER CLICKS IN QUESTJOURNALMENU' for QUESTLOGWORLD ---------+
22:23:11 [INFO] Starting InstantQueue 'QUESTLOGWORLD_RomanticCustomizedOhio'...
22:23:11 [INFO] Queue 'QUESTLOGWORLD_RomanticCustomizedOhio' Executing: DETERMINE "cancelled"
22:23:11 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'DETERMINE': outcome='cancelled' passively='false'
22:23:11 [INFO] Completing queue 'QUESTLOGWORLD_RomanticCustomizedOhio' in 0ms.
22:23:11 [INFO] +> Executing 'Event': Type='on player clicks KEYITEMSIT in inventory' Container='QUESTLOGWORLD(WORLD)'
player=p@JimyTheLightfootContext='{item=i@nether_star[display_name=Key Items;lore=Click here to view|Quest related
items˙script=KEYITEMSIT], cursor_item=i@air, raw_slot=49, is_shift_click=false, slot_type=CONTAINER, action=PICKUP_ALL,
slot=49, inventory=in@QUESTJOURNALMENU, click=LEFT, hotbar_button=0}'
22:23:11 [INFO] +- Building event 'ON PLAYER CLICKS KEYITEMSIT IN INVENTORY' for QUESTLOGWORLD ---------+
22:23:11 [INFO] Starting InstantQueue 'QUESTLOGWORLD_ConfirmedGothicAg'...
22:23:11 [INFO] Queue 'QUESTLOGWORLD_ConfirmedGothicAg' Executing: DETERMINE "passively" "cancelled"
22:23:11 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'DETERMINE': outcome='cancelled' passively='true'
22:23:11 [INFO] Queue 'QUESTLOGWORLD_ConfirmedGothicAg' Executing: INVENTORY "open" "d:in@KeyItemsInv"
22:23:11 [INFO] +- Executing dCommand: INVENTORY/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Filled tag <&co> with ':'.
22:23:11 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:11 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[ActiveQuests.Total]> with '2'.
22:23:11 [INFO] Filled tag <&co> with ':'.
22:23:11 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:11 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[CompletedQuests.Total]> with '0'.
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Filled tag <&0> with ''.
22:23:11 [INFO] Starting InstantQueue 'INV_SCRIPT_ITEM_PROC_LockingProcessed'...
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "KeyItems:!"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='KEYITEMS' Action/Value='DELETE(false)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: DEFINE "Total" "45"
22:23:11 [INFO] +- Executing dCommand: DEFINE/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'DEFINE': queue='INV_SCRIPT_ITEM_PROC_LockingProcessed' definition='total' value='45'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: DEFINE "Total"
"<def[total].sub[<yaml[<player.uuid>].read[KeyItems.Total]>]>"
22:23:11 [INFO] +- Executing dCommand: DEFINE/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:11 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[KeyItems.Total]> with '1'.
22:23:11 [INFO] Filled tag <def[total].sub[1]> with '44'.
22:23:11 [INFO] +> Executing 'DEFINE': queue='INV_SCRIPT_ITEM_PROC_LockingProcessed' definition='total' value='44'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player"
"KeyItemsTotal:<yaml[<player.uuid>].read[KeyItems.Total]>"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:11 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[KeyItems.Total]> with '1'.
22:23:11 [INFO] +> Executing 'FLAG': flag_name='KEYITEMSTOTAL' Action/Value='SET_VALUE(1)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FOREACH "<yaml[<player.uuid>].read[KeyItems.Items].as_list>"
22:23:11 [INFO] +- Executing dCommand: FOREACH/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <player.uuid> with '5fb9aeaa-52fd-494a-9388-1f5baab9ed7b'.
22:23:11 [INFO] Filled tag <yaml[5fb9aeaa-52fd-494a-9388-1f5baab9ed7b].read[KeyItems.Items].as_list> with 'li@AquaPassportIt'.
22:23:11 [INFO] +> Executing 'FOREACH': list='li@AquaPassportIt'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "KeyItems:|:<def[Value]>"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <def[Value]> with 'AquaPassportIt'.
22:23:11 [INFO] +> Executing 'FLAG': flag_name='KEYITEMS' Action/Value='SPLIT(AquaPassportIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "KeyItemsTotal:-:1"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='KEYITEMSTOTAL' Action/Value='DECREASE(1)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: IF "<player.flag[KeyItemsTotal]>" "<=" "0"
22:23:11 [INFO] +- Executing dCommand: IF/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'IF': use_braces='true'
22:23:11 [INFO] Filled tag <player.flag[KeyItemsTotal]> with '0'.
22:23:11 [INFO] Comparable='Decimal(0.0)', Operator='OR_LESS', ComparedTo='Decimal(0.0)' --> OUTCOME='true'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT "<def[Total]>"
22:23:11 [INFO] +- Executing dCommand: REPEAT/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <def[Total]> with '44'.
22:23:11 [INFO] +> Executing 'REPEAT': qty='44'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 2 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 3 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 4 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 5 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 6 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 7 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 8 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 9 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 10 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 11 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 12 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 13 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 14 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 15 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 16 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 17 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 18 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 19 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 20 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 21 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 22 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 23 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 24 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 25 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 26 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 27 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 28 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 29 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 30 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 31 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 32 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 33 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 34 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 35 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 36 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 37 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 38 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 39 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 40 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 41 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 42 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 43 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop 44 ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: FLAG "player" "ActiveQuests:|:i@PlaceHolderIt"
22:23:11 [INFO] +- Executing dCommand: FLAG/p@JimyTheLightfoot ---------+
22:23:11 [INFO] +> Executing 'FLAG': flag_name='ACTIVEQUESTS' Action/Value='SPLIT(i@PlaceHolderIt)' flag_target='p@JimyTheLightfoot'
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: REPEAT " CALLBACK"
22:23:11 [INFO] +- Repeat loop complete ---------+
22:23:11 [INFO] Queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' Executing: DETERMINE "<player.flag[KeyItems]>"
22:23:11 [INFO] +- Executing dCommand: DETERMINE/p@JimyTheLightfoot ---------+
22:23:11 [INFO] Filled tag <player.flag[KeyItems]> with 'AquaPassportIt'.
22:23:11 [INFO] +> Executing 'DETERMINE': outcome='AquaPassportIt' passively='false'
22:23:11 [INFO] Completing queue 'INV_SCRIPT_ITEM_PROC_LockingProcessed' in 22ms.
22:23:11 [INFO] +> Executing 'INVENTORY': actions='[open]' Inventory='in@KEYITEMSINV' slot='1'
22:23:11 [INFO] Completing queue 'QUESTLOGWORLD_ConfirmedGothicAg' in 24ms.