Java Version: 1.8.0_25
Up-time: 3h 43m
CraftBukkit Version: git-Spigot-8985e19 (MC: 1.8)
Denizen Version: Core: 1.02 (Build Unknown), CraftBukkit: 0.9.6-SNAPSHOT (build 57)
Active Plugins (45): AsyncWorldEditInjector: 2.1.3, LazyRoad: 0.8.1, LoreLocks: 2.2.2.3, WorldEdit: 6.0;3342-78f975b9,
BungeeSuitePortals: 0.5b-9, BukkitSpeak: 0.68-SNAPSHOT-b236, SkyWriter: 1.1,
Yamler: 2.2.3-SNAPSHOTb-72, ProtocolLib: 3.6.3-SNAPSHOT, WorldGuard: 6.0.0-beta-04.1544-,
Brewery: 1.3.1, Teleports: 0.50b-22, ImageOnMap: 2.7, VoxelSniper: 5.170.0-SNAPSHOT-jnks326-git1a17ed8,
PermissionsEx: 1.23.1, BetterShutdown: 1.0, LazyRoadInGameDesigner: 0.3.3,
CreeperHeal: 6.7.9, mcMMO: 1.5.00-b3547, Bans: 0.50b-8, Vault: 1.5.2-b33,
Warps: 0.17-SNAPSHOTb-11, Homes: 0.50b-6, Lores: 1.4.1, MagicSpells: 3.3-release,
HyperConomy: 0.975.3, HawkEye: 1.6.0, PlayEffect: 0.4.0, GiantTrees: 1.1,
CloudChatBukkit: 3.3.0, BungeeSuiteSpawn: 0.5b-8, Jail: 3.0.0-SNAPSHOT-b139,
Citizens: 2.0.14-SNAPSHOT (build 1200), BlocksHub: 0.5b, CraftBook: 3.9-SNAPSHOT,
BungeeTabListPlus_BukkitBridge: 7, HyperMerchant: 1.57-dev, Denizen: 0.9.6-SNAPSHOT (build 57),
CompatNoCheatPlus: 6.5.0-RC-sMD5NET-b68, Sentry: 1.8.3, NoCheatPlus: 3.12.0-SNAPSHOT-sMD5NET-b773,
Depenizen: 0.2.1 (build 230), AsyncWorldEdit: 2.1.3, dIRCBot: 0.2 (build 1),
Builder: 0.9.1
Loaded Worlds (1): Hub
Online Players (1): Chemical_Datas(Chemical_Datas)
Offline Players: 88
Mode: offline
20:03:00 [INFO] Starting InstantQueue 'EXCOMMAND_PersonsDemandReleased'...
20:03:00 [INFO] Queue 'EXCOMMAND_PersonsDemandReleased' Executing: RUN "testinject"
20:03:00 [INFO] +- Executing dCommand: RUN/p@Chemical_Datas ---------+
20:03:00 [INFO] +> Executing 'RUN': script='TESTINJECT(TASK)'
20:03:00 [INFO] Starting TimedQueue 'TESTINJECT_ExpandedHonestStruct'...
20:03:00 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "npc" "<player.selected_npc>"
20:03:00 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:00 [INFO] Filled tag <player.selected_npc> with 'n@93'.
20:03:00 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc' value='n@93'
20:03:00 [INFO] Completing queue 'EXCOMMAND_PersonsDemandReleased' in 9ms.
20:03:00 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: INJECT "get debug data"
20:03:00 [INFO] +- Executing dCommand: INJECT/p@Chemical_Datas ---------+
20:03:00 [INFO] +> Executing 'INJECT': script='GET DEBUG DATA(TASK)'
20:03:01 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "queue_accessors" " id/id| size/size| state/state| script/script| determination/determination| type/type| speed/speed|
commands/commands| debug/script.debug| definitions/definitions"
20:03:01 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:01 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='queue_accessors' value='id/id| size/size| state/state| script/script| determination/determination|
type/type| speed/speed| commands/commands| debug/script.debug| definitions/definitions'
20:03:01 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "script_accessor" " container_type/container_type| cooled_down/cooled_down| filename/filename|
relative_filename/relative_filename| name/name| loc/yaml_keys[script].size| step/step[<>>player<<>]| debug/debug| prefix/prefix| object_type/object_type|
assigned_to/get_npcs_assigned[<>>script<<>]"
20:03:01 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:01 [INFO] Filled tag <>> with '>'.
20:03:01 [INFO] Filled tag <<> with '<'.
20:03:01 [INFO] Filled tag <>> with '>'.
20:03:01 [INFO] Filled tag <<> with '<'.
20:03:01 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='script_accessor' value='container_type/container_type| cooled_down/cooled_down| filename/filename|
relative_filename/relative_filename| name/name| loc/yaml_keys[script].size| step/step[>player<]| debug/debug| prefix/prefix| object_type/object_type|
assigned_to/get_npcs_assigned[>script<]'
20:03:02 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "npc_accessors" " id/id| spawned/spawned| owner/owner| name/name| nickname/nickname| protected/is_protected|
lookclose/lookclose| previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location| teleport_on_stuck/teleport_on_stuck|
navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range| stratagey/navigator.attack_stratagey| s... *
snip! *
20:03:02 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:02 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_accessors' value='id/id| spawned/spawned| owner/owner| name/name| nickname/nickname|
protected/is_protected| lookclose/lookclose| previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location|
teleport_on_stuck/teleport_on_stuck| navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range|
stratagey/navigator.a... * snip! *
20:03:02 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "player_accessors" " name/name| display_name/name.display| list_name/name.list| entity_id/eid| save_name/save_name|
prefix/prefix| selected_npc/selected_npc| is_op/is_op| location/location.simple| chat_history/chat_history_list| target/target| open_inventory/open_inventory| is_op/is_op|
location/location.simple| chat_history/chat_history_list| target/target| open_inventory/open_inventory| item_on_cursor/item_on_cursor| money/money| list_flags/list_f... * snip! *
20:03:02 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:02 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='player_accessors' value='name/name| display_name/name.display| list_name/name.list| entity_id/eid|
save_name/save_name| prefix/prefix| selected_npc/selected_npc| is_op/is_op| location/location.simple| chat_history/chat_history_list| target/target|
open_inventory/open_inventory| is_op/is_op| location/location.simple| chat_history/chat_history_list| target/target| open_inventory/open_inventory| item_on_cursor/item_on_cursor|
money/money|... * snip! *
20:03:03 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor_types" "li@queue|script|player|npc"
20:03:03 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:03 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor_types' value='li@queue|script|player|npc'
20:03:03 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "<def[accessor_types]>" "{" "-" "define" "type" "%value%" "-" "define" "object" "<def[%value%]||none>" "-" "if"
"<def[object]>" "==" "none" "foreach" "next" "-" "foreach" "<def[%type%_accessors].as_list>" "{" "-" "define" "attribute" "<def[value].split[/].get[1]>" "-" "define" "accessor"
"<def[value].split[/].get[2]>" "-" "define" "object" "<el@%type%.parse>" "-" "define" "%type%_%attribute%" "<def[queue].%accessor%||none>" "}" "}"
20:03:03 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:03 [INFO] Filled tag <def[accessor_types]> with 'li@queue|script|player|npc'.
20:03:03 [INFO] +> Executing 'FOREACH': list='li@queue|script|player|npc'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "type" "%value%"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'queue'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='type' value='queue'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<def[%value%]||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'queue'.
20:03:04 [INFO] Filled tag <def[queue]||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[object]>" "==" "none" "foreach" "next"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[object]> with 'none'.
20:03:04 [INFO] Comparable 1: Comparable='Element(none)', Operator='EQUALS', ComparedTo='Element(none)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "next"
20:03:04 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:04 [INFO] +> Executing 'FOREACH': next='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 2 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "type" "%value%"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'script'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='type' value='script'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<def[%value%]||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'script'.
20:03:04 [INFO] Filled tag <def[script]||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[object]>" "==" "none" "foreach" "next"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[object]> with 'none'.
20:03:04 [INFO] Comparable 1: Comparable='Element(none)', Operator='EQUALS', ComparedTo='Element(none)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "next"
20:03:04 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:04 [INFO] +> Executing 'FOREACH': next='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 3 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "type" "%value%"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'player'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='type' value='player'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<def[%value%]||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'player'.
20:03:04 [INFO] Filled tag <def[player]||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[object]>" "==" "none" "foreach" "next"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[object]> with 'none'.
20:03:04 [INFO] Comparable 1: Comparable='Element(none)', Operator='EQUALS', ComparedTo='Element(none)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "next"
20:03:04 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:04 [INFO] +> Executing 'FOREACH': next='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 4 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "type" "%value%"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='type' value='npc'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<def[%value%]||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc'.
20:03:04 [INFO] Filled tag <def[npc]||none> with 'n@93'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='n@93'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[object]>" "==" "none" "foreach" "next"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[object]> with 'n@93'.
20:03:04 [INFO] Comparable 1: Comparable='Element(n@93)', Operator='EQUALS', ComparedTo='Element(none)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "<def[%type%_accessors].as_list>" "{" "-" "define" "attribute" "<def[value].split[/].get[1]>" "-" "define" "accessor"
"<def[value].split[/].get[2]>" "-" "define" "object" "<el@%type%.parse>" "-" "define" "%type%_%attribute%" "<def[queue].%accessor%||none>" "}"
20:03:04 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled tag <def[npc_accessors].as_list> with 'li@id/id| spawned/spawned| owner/owner| name/name| nickname/nickname| protected/is_protected| lookclose/lookclose|
previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location| teleport_on_stuck/teleport_on_stuck|
navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range| stratagey/navigator.attack_stratagey|
speed_modifier/navigator.spe... * snip! *
20:03:04 [INFO] +> Executing 'FOREACH': list='li@id/id| spawned/spawned| owner/owner| name/name| nickname/nickname| protected/is_protected| lookclose/lookclose|
previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location| teleport_on_stuck/teleport_on_stuck|
navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range| stratagey/navigator.attack_stratagey|
speed_modifier/navigator.speed_modifier| speed/navigator.base_... * snip! *
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with 'id'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='id'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'id'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='id'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'id'.
20:03:04 [INFO] Filled definition %accessor% with 'id'.
20:03:04 [INFO] Filled tag <def[queue].id||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_id' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 2 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' spawned'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='spawned'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'spawned'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='spawned'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'spawned'.
20:03:04 [INFO] Filled definition %accessor% with 'spawned'.
20:03:04 [INFO] Filled tag <def[queue].spawned||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_spawned' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 3 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' owner'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='owner'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'owner'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='owner'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'owner'.
20:03:04 [INFO] Filled definition %accessor% with 'owner'.
20:03:04 [INFO] Filled tag <def[queue].owner||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_owner' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 4 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' name'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='name'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'name'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='name'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'name'.
20:03:04 [INFO] Filled definition %accessor% with 'name'.
20:03:04 [INFO] Filled tag <def[queue].name||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_name' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 5 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' nickname'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='nickname'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'nickname'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='nickname'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'nickname'.
20:03:04 [INFO] Filled definition %accessor% with 'nickname'.
20:03:04 [INFO] Filled tag <def[queue].nickname||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_nickname' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 6 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' protected'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='protected'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'is_protected'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='is_protected'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'protected'.
20:03:04 [INFO] Filled definition %accessor% with 'is_protected'.
20:03:04 [INFO] Filled tag <def[queue].is_protected||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_protected' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 7 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' lookclose'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='lookclose'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'lookclose'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='lookclose'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'lookclose'.
20:03:04 [INFO] Filled definition %accessor% with 'lookclose'.
20:03:04 [INFO] Filled tag <def[queue].lookclose||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_lookclose' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 8 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' previous_location'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='previous_location'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with '.location.previous_location.simple'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='.location.previous_location.simple'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'previous_location'.
20:03:04 [INFO] Filled definition %accessor% with '.location.previous_location.simple'.
20:03:04 [INFO] Filled tag <def[queue]..location.previous_location.simple||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_previous_location' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 9 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' location'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='location'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'location.simple'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='location.simple'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'location'.
20:03:04 [INFO] Filled definition %accessor% with 'location.simple'.
20:03:04 [INFO] Filled tag <def[queue].location.simple||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_location' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 10 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' nav_target'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='nav_target'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.target_location'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.target_location'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'nav_target'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.target_location'.
20:03:04 [INFO] Filled tag <def[queue].navigator.target_location||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_nav_target' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 11 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' teleport_on_stuck'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='teleport_on_stuck'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'teleport_on_stuck'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='teleport_on_stuck'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'teleport_on_stuck'.
20:03:04 [INFO] Filled definition %accessor% with 'teleport_on_stuck'.
20:03:04 [INFO] Filled tag <def[queue].teleport_on_stuck||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_teleport_on_stuck' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 12 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' navigating'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='navigating'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.is_navigating'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.is_navigating'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'navigating'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.is_navigating'.
20:03:04 [INFO] Filled tag <def[queue].navigator.is_navigating||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_navigating' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 13 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' base_speed'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='base_speed'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.base_speed'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.base_speed'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'base_speed'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.base_speed'.
20:03:04 [INFO] Filled tag <def[queue].navigator.base_speed||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_base_speed' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 14 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' range'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='range'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.range'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.range'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'range'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.range'.
20:03:04 [INFO] Filled tag <def[queue].navigator.range||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_range' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 15 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' attack_range'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='attack_range'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.attack_range'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.attack_range'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'attack_range'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.attack_range'.
20:03:04 [INFO] Filled tag <def[queue].navigator.attack_range||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_attack_range' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 16 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' stratagey'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='stratagey'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.attack_stratagey'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.attack_stratagey'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'stratagey'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.attack_stratagey'.
20:03:04 [INFO] Filled tag <def[queue].navigator.attack_stratagey||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_stratagey' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 17 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' speed_modifier'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='speed_modifier'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.speed_modifier'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.speed_modifier'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'speed_modifier'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.speed_modifier'.
20:03:04 [INFO] Filled tag <def[queue].navigator.speed_modifier||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_speed_modifier' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 18 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' speed'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='speed'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.base_speed'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.base_speed'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'speed'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.base_speed'.
20:03:04 [INFO] Filled tag <def[queue].navigator.base_speed||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_speed' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 19 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' avoid_water'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='avoid_water'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator.avoid_water'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator.avoid_water'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'avoid_water'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator.avoid_water'.
20:03:04 [INFO] Filled tag <def[queue].navigator.avoid_water||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_avoid_water' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 20 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' base_speed'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='base_speed'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'navigator'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='navigator'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'base_speed'.
20:03:04 [INFO] Filled definition %accessor% with 'navigator'.
20:03:04 [INFO] Filled tag <def[queue].navigator||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_base_speed' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 21 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' has_assignment'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='has_assignment'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'has_script'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='has_script'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'has_assignment'.
20:03:04 [INFO] Filled definition %accessor% with 'has_script'.
20:03:04 [INFO] Filled tag <def[queue].has_script||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_has_assignment' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 22 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' script'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='script'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'script'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='script'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'script'.
20:03:04 [INFO] Filled definition %accessor% with 'script'.
20:03:04 [INFO] Filled tag <def[queue].script||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_script' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 23 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' flags'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='flags'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'list_flags'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='list_flags'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'flags'.
20:03:04 [INFO] Filled definition %accessor% with 'list_flags'.
20:03:04 [INFO] Filled tag <def[queue].list_flags||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_flags' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 24 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' engaged'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='engaged'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'is_engaged'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='is_engaged'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'engaged'.
20:03:04 [INFO] Filled definition %accessor% with 'is_engaged'.
20:03:04 [INFO] Filled tag <def[queue].is_engaged||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_engaged' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 25 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' anchors'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='anchors'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'anchor.list'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='anchor.list'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'anchors'.
20:03:04 [INFO] Filled definition %accessor% with 'anchor.list'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! anchor: tags are deprecated! Use <npc.anchor[]>!
20:03:04 [INFO] Filled tag <def[queue].anchor.list||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_anchors' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 26 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' inventory'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='inventory'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'inventory.list_contents'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='inventory.list_contents'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'inventory'.
20:03:04 [INFO] Filled definition %accessor% with 'inventory.list_contents'.
20:03:04 [INFO] Filled tag <def[queue].inventory.list_contents||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_inventory' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 27 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' traits'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='traits'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'traits'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='traits'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'traits'.
20:03:04 [INFO] Filled definition %accessor% with 'traits'.
20:03:04 [INFO] Filled tag <def[queue].traits||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_traits' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 28 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "attribute" "<def[value].split[/].get[1]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[1]> with ' nearby_npcs'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='attribute' value='nearby_npcs'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "accessor" "<def[value].split[/].get[2]>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[/].get[2]> with 'location.find.npcs.within[25]'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='accessor' value='location.find.npcs.within[25]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "object" "<el@%type%.parse>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Unfilled attributes '[parse]' for tag <el@npc.parse>!
20:03:04 [INFO] Filled tag <el@npc.parse> with 'null'.
20:03:04 [INFO] ERROR in script 'GET DEBUG DATA'! Tag <el@npc.parse> is invalid!
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='object' value='null'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: DEFINE "%type%_%attribute%" "<def[queue].%accessor%||none>"
20:03:04 [INFO] +- Executing dCommand: DEFINE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %type% with 'npc'.
20:03:04 [INFO] Filled definition %attribute% with 'nearby_npcs'.
20:03:04 [INFO] Filled definition %accessor% with 'location.find.npcs.within[25]'.
20:03:04 [INFO] Filled tag <def[queue].location.find.npcs.within[25||none> with 'none'.
20:03:04 [INFO] +> Executing 'DEFINE': queue='TESTINJECT_ExpandedHonestStruct' definition='npc_nearby_npcs' value='none'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH "<queue.definitions>" "{" "-" "if" "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]>
<def[%value%]>" "}" "}"
20:03:04 [INFO] +- Executing dCommand: FOREACH/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <queue.definitions> with
'li@npc_script|npc_anchors|npc_previous_location|npc_accessors|accessor|npc_flags|npc_teleport_on_stuck|type|npc_lookclose|npc_engaged|npc_avoid_water|player_accessors|queue_accessors|attribute|npc_range|npc_has_assignment|value|npc|npc_protected|npc_nickname|npc_base_speed|npc_traits|loop_index|script_accessor|npc_location|npc_nav_target|npc_speed_modifier|npc_name|npc_speed|npc_owner|npc_attack_range|npc_nearby_npcs|npc_id|npc_inventory|npc_spawned|npc_navigating|npc_s...
* snip! *
20:03:04 [INFO] +> Executing 'FOREACH':
list='li@npc_script|npc_anchors|npc_previous_location|npc_accessors|accessor|npc_flags|npc_teleport_on_stuck|type|npc_lookclose|npc_engaged|npc_avoid_water|player_accessors|queue_accessors|attribute|npc_range|npc_has_assignment|value|npc|npc_protected|npc_nickname|npc_base_speed|npc_traits|loop_index|script_accessor|npc_location|npc_nav_target|npc_speed_modifier|npc_name|npc_speed|npc_owner|npc_attack_range|npc_nearby_npcs|npc_id|npc_inventory|npc_spawned|npc_navigating|npc_stratagey|accessor_types|o...
* snip! *
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_script'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_script'.
20:03:04 [INFO] Filled tag <def[npc_script]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_script none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 2 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_anchors'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_anchors'.
20:03:04 [INFO] Filled tag <def[npc_anchors]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_anchors none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 3 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_previous_location'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_previous_location'.
20:03:04 [INFO] Filled tag <def[npc_previous_location]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_previous_location none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 4 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_accessors'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_accessors'.
20:03:04 [INFO] Filled tag <def[npc_accessors]> with 'id/id| spawned/spawned| owner/owner| name/name| nickname/nickname| protected/is_protected| lookclose/lookclose|
previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location| teleport_on_stuck/teleport_on_stuck|
navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range| stratagey/navigator.attack_stratagey|
speed_modifier/navigator.speed_modifier... * snip! *
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_accessors id/id| spawned/spawned| owner/owner| name/name| nickname/nickname| protected/is_protected| lookclose/lookclose|
previous_location/.location.previous_location.simple| location/location.simple| nav_target/navigator.target_location| teleport_on_stuck/teleport_on_stuck|
navigating/navigator.is_navigating| base_speed/navigator.base_speed| range/navigator.range| attack_range/navigator.attack_range| stratagey/navigator.attack_stratagey|
speed_modifier/navigator.speed_modifier| speed... * snip! *
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 5 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 6 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_flags'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_flags'.
20:03:04 [INFO] Filled tag <def[npc_flags]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_flags none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 7 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_teleport_on_stuck'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_teleport_on_stuck'.
20:03:04 [INFO] Filled tag <def[npc_teleport_on_stuck]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_teleport_on_stuck none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 8 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 9 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_lookclose'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_lookclose'.
20:03:04 [INFO] Filled tag <def[npc_lookclose]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_lookclose none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 10 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_engaged'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_engaged'.
20:03:04 [INFO] Filled tag <def[npc_engaged]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_engaged none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 11 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_avoid_water'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_avoid_water'.
20:03:04 [INFO] Filled tag <def[npc_avoid_water]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_avoid_water none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 12 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 13 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 14 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 15 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_range'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_range'.
20:03:04 [INFO] Filled tag <def[npc_range]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_range none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 16 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_has_assignment'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_has_assignment'.
20:03:04 [INFO] Filled tag <def[npc_has_assignment]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_has_assignment none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 17 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 18 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc'.
20:03:04 [INFO] Filled tag <def[npc]> with 'n@93'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc n@93' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 19 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_protected'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_protected'.
20:03:04 [INFO] Filled tag <def[npc_protected]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_protected none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 20 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_nickname'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_nickname'.
20:03:04 [INFO] Filled tag <def[npc_nickname]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_nickname none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 21 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_base_speed'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_base_speed'.
20:03:04 [INFO] Filled tag <def[npc_base_speed]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_base_speed none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 22 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_traits'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_traits'.
20:03:04 [INFO] Filled tag <def[npc_traits]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_traits none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 23 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 24 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:04 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 25 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_location'.
20:03:04 [INFO] Filled tag <def[value]> with 'npc_location'.
20:03:04 [INFO] Filled tag <def[npc_location]> with 'none'.
20:03:04 [INFO] +> Executing 'NARRATE': Narrating='npc_location none' Targets='[p@Chemical_Datas]'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:04 [INFO] +- Foreach loop 26 ---------+
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:04 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:04 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:04 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:04 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:04 [INFO] Filled definition %value% with 'npc_nav_target'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_nav_target'.
20:03:05 [INFO] Filled tag <def[npc_nav_target]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_nav_target none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 27 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_speed_modifier'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_speed_modifier'.
20:03:05 [INFO] Filled tag <def[npc_speed_modifier]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_speed_modifier none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 28 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_name'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_name'.
20:03:05 [INFO] Filled tag <def[npc_name]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_name none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 29 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_speed'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_speed'.
20:03:05 [INFO] Filled tag <def[npc_speed]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_speed none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 30 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_owner'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_owner'.
20:03:05 [INFO] Filled tag <def[npc_owner]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_owner none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 31 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_attack_range'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_attack_range'.
20:03:05 [INFO] Filled tag <def[npc_attack_range]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_attack_range none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 32 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_nearby_npcs'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_nearby_npcs'.
20:03:05 [INFO] Filled tag <def[npc_nearby_npcs]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_nearby_npcs none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 33 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_id'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_id'.
20:03:05 [INFO] Filled tag <def[npc_id]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_id none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 34 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_inventory'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_inventory'.
20:03:05 [INFO] Filled tag <def[npc_inventory]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_inventory none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 35 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_spawned'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_spawned'.
20:03:05 [INFO] Filled tag <def[npc_spawned]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_spawned none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 36 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_navigating'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_navigating'.
20:03:05 [INFO] Filled tag <def[npc_navigating]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_navigating none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 37 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'true'.
20:03:05 [INFO] Comparable 1: Comparable='Element(true)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: NARRATE "<def[value]> <def[%value%]>"
20:03:05 [INFO] +- Executing dCommand: NARRATE/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled definition %value% with 'npc_stratagey'.
20:03:05 [INFO] Filled tag <def[value]> with 'npc_stratagey'.
20:03:05 [INFO] Filled tag <def[npc_stratagey]> with 'none'.
20:03:05 [INFO] +> Executing 'NARRATE': Narrating='npc_stratagey none' Targets='[p@Chemical_Datas]'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 38 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:05 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] +- Foreach loop 39 ---------+
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: IF "<def[value].split[_].contains[npc]>" "{" "-" "narrate" "<def[value]> <def[%value%]>" "}"
20:03:05 [INFO] +- Executing dCommand: IF/p@Chemical_Datas ---------+
20:03:05 [INFO] Filled tag <def[value].split[_].contains[npc]> with 'false'.
20:03:05 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
20:03:05 [INFO] Queue 'TESTINJECT_ExpandedHonestStruct' Executing: FOREACH " CALLBACK"
20:03:05 [INFO] Completing queue 'TESTINJECT_ExpandedHonestStruct' in 4525ms.