Java Version: 1.7.0_65
CraftBukkit Version: git-Spigot-1.7.9-R0.2-205-g2c31986 (MC: 1.7.10)
Active Plugins (20): zPermissions: 1.2, WorldBorder: 1.7.8, SignEdit: 3.3.8, Warps: 0.17-SNAPSHOTb-11,
WorldEdit: 6.0.0-SNAPSHOT, Craftconomy3: 3.1.7-SNAPSHOT jenkins-Craftconomy3-2162,
BungeeSuiteSpawn: 0.5b-5, NoBoatBreak: 1.0, Vault: 1.4.1-b${env.TRAVIS_BUILD_NUMBER},
Multiverse-Core: 2.5-b688, Herochat: 5.6.7-SNAPSHOT, MyHelpPages: 1.4.2, WorldGuard: 5.9.1-SNAPSHOT,
Citizens: 2.0.13-SNAPSHOT (build 1171), LiftSign: 0.4.1, WorldGuardFlagger: 3.4,
BungeeChatClient: 0.3, Denizen: 0.9.6-SNAPSHOT (build mc1525.5), WorldRegions: 1.0.0,
Depenizen: 0.2.0 (build 214)
Loaded Worlds (1): FairGrounds
Online Players (1): Nilsentience(Nilsentience)
21:44:37 [INFO] +> Executing 'Event': Type='on UNITTEST command' Container='UNIT TEST
HANDLER(WORLD)' Player='Nilsentience' Context='{cuboids=li@, args=li@,
raw_args=, command=UNITTEST, server=false, parsed_args=li@}'
21:44:37 [INFO] +- Building event 'ON UNITTEST COMMAND' for UNIT TEST HANDLER ---------+
21:44:37 [INFO] Starting InstantQueue 'ZEDZIZDNNI'...
21:44:37 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <context.server> with 'false'.
21:44:37 [INFO] Comparable 1: Logic='NEGATIVE', Comparable='Element(false)',
Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='true'
21:44:37 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <player.is_op> with 'true'.
21:44:37 [INFO] Comparable 1: Logic='NEGATIVE', Comparable='Element(true)',
Operator='EQUALS', ComparedTo='Element(true)' --> OUTCOME='false'
21:44:37 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'NARRATE': Narrating='Starting unit tests.'
Targets='[p@Nilsentience]'
21:44:37 [INFO] +- Executing dCommand: YAML/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'YAML': id='Unit Test Yaml' action='CREATE' value=''
21:44:37 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='SET_VALUE(0)'
flag_target='server'
21:44:37 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:37 [INFO] Starting TimedQueue 'EEDDDNIEZD'...
21:44:37 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:37 [INFO] Filled tag <server.flag[ut_counter]> with '0'.
21:44:37 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:37 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Number(0)' --> OUTCOME='false'
21:44:37 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:37 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:37 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:37 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:37 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:37 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:37 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:37 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:37 [INFO] +> Executing 'DEFINE': queue='EEDDDNIEZD' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:37 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:37 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:37 [INFO] Filled tag <server.flag[ut_counter].as_int> with '1'.
21:44:37 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@BRACKETS UNIT TEST'.
21:44:37 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@BRACKETS UNIT TEST'
Targets='[p@Nilsentience]'
21:44:37 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:37 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:37 [INFO] Filled tag <server.flag[ut_counter].as_int> with '1'.
21:44:37 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@BRACKETS UNIT TEST'.
21:44:37 [INFO] +> Executing 'RUN': script='BRACKETS UNIT TEST(WORLD)' id='test_queue'
21:44:37 [INFO] +- Executing dCommand: DETERMINE/p@Nilsentience ---------+
21:44:37 [INFO] +> Executing 'DETERMINE': outcome='fulfilled' passively='false'
21:44:37 [INFO] Completing queue 'ZEDZIZDNNI'.
21:44:38 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:38 [INFO] +> Executing 'WAIT': queue='EEDDDNIEZD' delay='10t'
21:44:38 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:38 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:38 [INFO] Starting TimedQueue 'DEENNEZENN'...
21:44:38 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:38 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:38 [INFO] Filled tag <server.flag[ut_counter]> with '1.0'.
21:44:38 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:38 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(1.0)' --> OUTCOME='false'
21:44:38 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:38 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:38 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:38 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:38 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:38 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:38 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:38 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:38 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:38 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:38 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:38 [INFO] +> Executing 'DEFINE': queue='DEENNEZENN' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:38 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:38 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:38 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:38 [INFO] Filled tag <server.flag[ut_counter].as_int> with '2'.
21:44:38 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@PROCEDURE SCRIPT UNIT TEST'.
21:44:38 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@PROCEDURE SCRIPT UNIT
TEST' Targets='[p@Nilsentience]'
21:44:38 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:38 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:38 [INFO] Filled tag <server.flag[ut_counter].as_int> with '2'.
21:44:38 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@PROCEDURE SCRIPT UNIT TEST'.
21:44:38 [INFO] +> Executing 'RUN': script='PROCEDURE SCRIPT UNIT TEST(WORLD)'
id='test_queue'
21:44:38 [INFO] Completing queue 'EEDDDNIEZD'.
21:44:39 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:39 [INFO] +> Executing 'WAIT': queue='DEENNEZENN' delay='10t'
21:44:39 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:39 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:39 [INFO] Starting TimedQueue 'NDDNEDENNZ'...
21:44:39 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:39 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:39 [INFO] Filled tag <server.flag[ut_counter]> with '2.0'.
21:44:39 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:39 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(2.0)' --> OUTCOME='false'
21:44:39 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:39 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:39 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:39 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:39 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:39 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:39 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:39 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:39 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:39 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:39 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:39 [INFO] +> Executing 'DEFINE': queue='NDDNEDENNZ' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:39 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:39 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:39 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:39 [INFO] Filled tag <server.flag[ut_counter].as_int> with '3'.
21:44:39 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@INJECTION UNIT TEST'.
21:44:39 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@INJECTION UNIT TEST'
Targets='[p@Nilsentience]'
21:44:39 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:39 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:39 [INFO] Filled tag <server.flag[ut_counter].as_int> with '3'.
21:44:39 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@INJECTION UNIT TEST'.
21:44:39 [INFO] +> Executing 'RUN': script='INJECTION UNIT TEST(WORLD)' id='test_queue'
21:44:39 [INFO] Completing queue 'DEENNEZENN'.
21:44:48 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:48 [INFO] +> Executing 'WAIT': queue='NDDNEDENNZ' delay='10t'
21:44:49 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:49 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:49 [INFO] Starting TimedQueue 'IDENENEEEE'...
21:44:49 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:49 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:49 [INFO] Filled tag <server.flag[ut_counter]> with '3.0'.
21:44:49 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:49 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(3.0)' --> OUTCOME='false'
21:44:49 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:49 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:49 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:49 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:49 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:49 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:49 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:49 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:49 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:49 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:49 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:49 [INFO] +> Executing 'DEFINE': queue='IDENENEEEE' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:49 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:49 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:49 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:49 [INFO] Filled tag <server.flag[ut_counter].as_int> with '4'.
21:44:49 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@BOOK UNIT TEST'.
21:44:49 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@BOOK UNIT TEST'
Targets='[p@Nilsentience]'
21:44:49 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:49 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:49 [INFO] Filled tag <server.flag[ut_counter].as_int> with '4'.
21:44:49 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@BOOK UNIT TEST'.
21:44:49 [INFO] +> Executing 'RUN': script='BOOK UNIT TEST(WORLD)' id='test_queue'
21:44:49 [INFO] Completing queue 'NDDNEDENNZ'.
21:44:49 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:49 [INFO] +> Executing 'WAIT': queue='IDENENEEEE' delay='10t'
21:44:50 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:50 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:50 [INFO] Starting TimedQueue 'ZNIZEEEEIZ'...
21:44:50 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:50 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:50 [INFO] Filled tag <server.flag[ut_counter]> with '4.0'.
21:44:50 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:50 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(4.0)' --> OUTCOME='false'
21:44:50 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:50 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:50 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:50 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:50 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:50 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:50 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:50 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:50 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:50 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:50 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:50 [INFO] +> Executing 'DEFINE': queue='ZNIZEEEEIZ' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:50 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:50 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:50 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:50 [INFO] Filled tag <server.flag[ut_counter].as_int> with '5'.
21:44:50 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@FLAG UNIT TEST'.
21:44:50 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@FLAG UNIT TEST'
Targets='[p@Nilsentience]'
21:44:50 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:50 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:50 [INFO] Filled tag <server.flag[ut_counter].as_int> with '5'.
21:44:50 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@FLAG UNIT TEST'.
21:44:50 [INFO] +> Executing 'RUN': script='FLAG UNIT TEST(WORLD)' id='test_queue'
21:44:50 [INFO] Completing queue 'IDENENEEEE'.
21:44:51 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:51 [INFO] +> Executing 'WAIT': queue='ZNIZEEEEIZ' delay='10t'
21:44:52 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:52 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:52 [INFO] Starting TimedQueue 'NNINENNEEE'...
21:44:52 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:52 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:52 [INFO] Filled tag <server.flag[ut_counter]> with '5.0'.
21:44:52 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:52 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(5.0)' --> OUTCOME='false'
21:44:52 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:52 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:52 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:52 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:52 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:52 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:52 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:52 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:52 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:52 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:52 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:52 [INFO] +> Executing 'DEFINE': queue='NNINENNEEE' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:52 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:52 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:52 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:52 [INFO] Filled tag <server.flag[ut_counter].as_int> with '6'.
21:44:52 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@DINVENTORY UNIT TASK'.
21:44:52 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@DINVENTORY UNIT TASK'
Targets='[p@Nilsentience]'
21:44:52 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:52 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:52 [INFO] Filled tag <server.flag[ut_counter].as_int> with '6'.
21:44:52 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@DINVENTORY UNIT TASK'.
21:44:52 [INFO] +> Executing 'RUN': script='DINVENTORY UNIT TASK(WORLD)' id='test_queue'
21:44:52 [INFO] Completing queue 'ZNIZEEEEIZ'.
21:44:53 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:53 [INFO] +> Executing 'WAIT': queue='NNINENNEEE' delay='10t'
21:44:54 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:54 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:54 [INFO] Starting TimedQueue 'NNINNENIEE'...
21:44:54 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:54 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:54 [INFO] Filled tag <server.flag[ut_counter]> with '6.0'.
21:44:54 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:54 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(6.0)' --> OUTCOME='false'
21:44:54 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:54 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:54 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:54 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:54 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:54 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:54 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:54 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:54 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:54 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:54 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:54 [INFO] +> Executing 'DEFINE': queue='NNINNENIEE' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:54 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:54 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:54 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:54 [INFO] Filled tag <server.flag[ut_counter].as_int> with '7'.
21:44:54 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@DMATERIAL UNIT TEST'.
21:44:54 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@DMATERIAL UNIT TEST'
Targets='[p@Nilsentience]'
21:44:54 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:54 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:54 [INFO] Filled tag <server.flag[ut_counter].as_int> with '7'.
21:44:54 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@DMATERIAL UNIT TEST'.
21:44:54 [INFO] +> Executing 'RUN': script='DMATERIAL UNIT TEST(WORLD)' id='test_queue'
21:44:54 [INFO] Completing queue 'NNINENNEEE'.
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:54 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:55 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:55 [INFO] +> Executing 'WAIT': queue='NNINNENIEE' delay='10t'
21:44:56 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:56 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:56 [INFO] Starting TimedQueue 'ZNDEEEZNEI'...
21:44:56 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:56 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:56 [INFO] Filled tag <server.flag[ut_counter]> with '7.0'.
21:44:56 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:56 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(7.0)' --> OUTCOME='false'
21:44:56 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:56 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:56 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:56 [INFO] Comparable 1: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='false'
21:44:56 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:56 [INFO] Filled tag <player||null> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:56 [INFO] Filled tag <server.list_players.get[1]> with
'p@20572919-a8f6-4319-ab59-d33bbf81421d'.
21:44:56 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:56 [INFO] Comparable 1: Comparable='Element(p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4)',
Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false'
21:44:56 [INFO] +- Executing dCommand: DEFINE/p@Nilsentience ---------+
21:44:56 [INFO] Filled tag <player> with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:56 [INFO] +> Executing 'DEFINE': queue='ZNDEEEZNEI' definition='player'
value='p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'
21:44:56 [INFO] +- Executing dCommand: FLAG/p@Nilsentience ---------+
21:44:56 [INFO] +> Executing 'FLAG': flag_name='UT_COUNTER' Action/Value='INCREASE(1)'
flag_target='server'
21:44:56 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:56 [INFO] Filled tag <server.flag[ut_counter].as_int> with '8'.
21:44:56 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@CONSTANT TAGS UNIT TASK'.
21:44:56 [INFO] +> Executing 'NARRATE': Narrating='Running test -> s@CONSTANT TAGS UNIT TASK'
Targets='[p@Nilsentience]'
21:44:56 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:56 [INFO] Filled definition %player% with 'p@af5efd2a-f19b-47ef-a4bb-9c585108b3d4'.
21:44:56 [INFO] Filled tag <server.flag[ut_counter].as_int> with '8'.
21:44:56 [INFO] Filled tag
<server.flag[unit_tasks].as_list.get[<server.flag[ut_counter].as_int>]> with
's@CONSTANT TAGS UNIT TASK'.
21:44:56 [INFO] +> Executing 'RUN': script='CONSTANT TAGS UNIT TASK(WORLD)' id='test_queue'
21:44:56 [INFO] Completing queue 'NNINNENIEE'.
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] ERROR in script 'CONSTANT TAGS UNIT TASK'! constant: tags are deprecated! Use
<npc.constant[]>!
21:44:56 [INFO] ERROR in script 'CONSTANT TAGS UNIT TASK'! EXPECTED VALUE is an invalid
dCommand! Are you sure it loaded?
21:44:56 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:56 [INFO] +> [dNPCRegistry] Deconstructing Denizen NPC constant npc/20
21:44:56 [INFO] +> [dNPC] Uh oh! Denizen has encountered a NPE while trying to fetch an NPC.
Has this NPC been removed?
21:44:56 [INFO] +- Executing dCommand: WAIT/p@Nilsentience ---------+
21:44:56 [INFO] +> Executing 'WAIT': queue='ZNDEEEZNEI' delay='10t'
21:44:57 [INFO] +> [dNPC] Uh oh! Denizen has encountered a NPE while trying to fetch an NPC.
Has this NPC been removed?
21:44:57 [INFO] +> [dNPCRegistry] Removed NPC from dRegistry.
21:44:57 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:57 [INFO] +> Executing 'RUN': Container='UNIT TEST HANDLER(WORLD)' path='do tests'
local='true'
21:44:57 [INFO] Starting TimedQueue 'EINEZIENNI'...
21:44:57 [INFO] +- Executing dCommand: IF/p@Nilsentience ---------+
21:44:57 [INFO] Filled tag <server.flag[unit_tasks].as_list.size> with '8'.
21:44:57 [INFO] Filled tag <server.flag[ut_counter]> with '8.0'.
21:44:57 [INFO] Filled tag <queue.exists[test_queue]> with 'false'.
21:44:57 [INFO] Comparable 1: Comparable='Number(8)', Operator='OR_LESS',
ComparedTo='Decimal(8.0)' --> OUTCOME='true'
21:44:57 [INFO] Comparable 2: Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
21:44:57 [INFO] +- Executing dCommand: RUN/p@Nilsentience ---------+
21:44:57 [INFO] +> Executing 'RUN': script='UNIT TEST SAVE OUTPUT(TASK)'
21:44:57 [INFO] +- Executing dCommand: NARRATE/p@Nilsentience ---------+
21:44:57 [INFO] +> Executing 'NARRATE': Narrating='Finished unit tests.'
Targets='[p@Nilsentience]'
21:44:57 [INFO] +- Executing dCommand: QUEUE/p@Nilsentience ---------+
21:44:57 [INFO] +> Executing 'QUEUE': Queue='EINEZIENNI' Action='CLEAR'
21:44:57 [INFO] Completing queue 'EINEZIENNI'.
21:44:57 [INFO] Completing queue 'ZNDEEEZNEI'.
21:44:57 [INFO] Re-completing queue 'EINEZIENNI'.
21:44:57 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:57 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:57 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:57 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'
21:44:57 [INFO] ERROR! Unknown YAML ID 'UNIT TEST YAML'