Paste #33896: Denizen Debug Logs From Jn6N79

Date: 2016/05/30 16:22:35 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link


Java Version: 1.8.0_91
Up-time: 3h 35m
CraftBukkit Version: git-Paper-750 (MC: 1.9.4)
Denizen Version: Core: 1.10 (Build ${build.number}), CraftBukkit: 0.9.8-SNAPSHOT (build 594)
Active Plugins (29): dynmap: 2.3-SNAPSHOT-1137, WorldEdit: 6.1;no_git_id, RemoteToolkitPlugin: R10_A15.3, 
GetInventory: 1.5, zPermissions: 1.3-SNAPSHOT, CustomCrafting: 1.0, Commons: 1.8.2-SNAPSHOT-304e289, 
WorldBorder: 1.8.5, Votifier: 2.3.2.2, Multiverse-Core: 2.5-b719, WorldGuard: 6.1, 
VoxelSniper: 7.1.0, DevotedResources: 1.0, Microblocks: 2.2, EffectLib: 4.2, 
HeroChatBridgeBukkit: 1.0.2, Vault: 1.5.6-b49, Prism: 2.0.6-35, HeroTitles: 1.1.1-b000, 
Essentials: 2.0.1-b339, Herochat: 5.6.7-SNAPSHOT, Citizens: 2.0.18-SNAPSHOT (build 1369), 
PhatLoots: 4.1.0, Champions: 0.0.1, Denizen: 0.9.8-SNAPSHOT (build 594), dIRCBot: 0.3 (build 9), 
Depenizen: 0.2.2 (build Unknown), MythicMobs: 2.4.4, Heroes: 1.8.6-SNAPSHOT-b32,
Loaded Worlds (3): Zolaria, Abeyance, instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3
Online Players (2): DanielWindBlade(DanielWindBlade), TheBlackCoyote(TheBlackCoyote)
Offline Players: 25
Mode: offline

18:22:24 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:24 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:24 [INFO] +- While loop 459 ---------+ 
18:22:24 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:24 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:24 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:24 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:24 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:24 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:24 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:25 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:25 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:25 [INFO] +- While loop 130 ---------+ 
18:22:25 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:25 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:25 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:25 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:25 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:25 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:25 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s' 
18:22:26 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:26 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:26 [INFO] +- While loop 460 ---------+ 
18:22:26 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:26 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:26 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:26 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:26 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:26 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:26 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:26 [INFO]  Running script event 'EntityTeleports', event='player 
                   teleports' for script 'INSTANCING_EVENT_HANDLER' 
18:22:26 [INFO]  Starting InstantQueue 
                   'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe'... 
18:22:26 [INFO]  Queue 'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' 
                   Executing: IF 
                   "<context.destination.world.name.starts_with[instancing]>" 
                   "&&" "<context.origin.world.name.starts_with[instancing].not>" 
                   "{" "-" "flag" "<player>" 
                   "instance_entry_location:<context.origin>" "}" 
18:22:26 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
18:22:26 [INFO] +> Executing 'IF': use_braces='true' 
18:22:26 [INFO]  Filled tag 
                   <context.destination.world.name.starts_with[instancing]> with 
                   'false'. 
18:22:26 [INFO]  Queue 'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' 
                   Executing: IF 
                   "<context.origin.world.name.starts_with[instancing/instances]>" 
                   "&&" 
                   "<context.destination.world.name.starts_with[instancing/instances].not>" 
                   "{" "-" "define" "instance" "<context.origin.world>" "-" "if" 
                   "<s@instancing_config.yaml_key[close when 
                   empty].as_boolean||false>" "{" "-" "if" 
                   "<def[instance].players.size||0>" "<" "1" "{" "-" "run" 
                   "remove_instance" "def:<def[instance]>" "}" "}" "}" 
18:22:26 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
18:22:26 [INFO] +> Executing 'IF': use_braces='true' 
18:22:26 [INFO]  Filled tag 
                   <context.origin.world.name.starts_with[instancing/instances]> 
                   with 'true'. 
18:22:26 [INFO]  Filled tag 
                   <context.destination.world.name.starts_with[instancing/instances].not> 
                   with 'true'. 
18:22:26 [INFO]  Queue 'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' 
                   Executing: DEFINE "instance" "<context.origin.world>" 
18:22:26 [INFO] +- Executing dCommand: DEFINE/p@TheBlackCoyote ---------+ 
18:22:26 [INFO]  Filled tag <context.origin.world> with 
                   'w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:26 [INFO] +> Executing 'DEFINE': 
                   queue='INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe'  
                   definition='instance'  
                   value='w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:26 [INFO]  Queue 'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' 
                   Executing: IF "<s@instancing_config.yaml_key[close when 
                   empty].as_boolean||false>" "{" "-" "if" 
                   "<def[instance].players.size||0>" "<" "1" "{" "-" "run" 
                   "remove_instance" "def:<def[instance]>" "}" "}" 
18:22:26 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
18:22:26 [INFO] +> Executing 'IF': use_braces='true' 
18:22:26 [INFO]  Filled tag <s@instancing_config.yaml_key[close when 
                   empty].as_boolean||false> with 'true'. 
18:22:26 [INFO]  Queue 'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' 
                   Executing: IF "<def[instance].players.size||0>" "<" "1" "{" 
                   "-" "run" "remove_instance" "def:<def[instance]>" "}" 
18:22:26 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
18:22:26 [INFO] +> Executing 'IF': use_braces='true' 
18:22:26 [INFO]  Filled tag <def[instance].players.size||0> with '1'. 
18:22:26 [INFO]  Comparable='Decimal(1.0)', Operator='LESS', 
                   ComparedTo='Decimal(1.0)' --> OUTCOME='false' 
18:22:26 [INFO]  Completing queue 
                   'INSTANCING_EVENT_HANDLER_DenizenCharacterizationTribe' in 
                   2ms. 
18:22:27 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:27 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:27 [INFO] +- While loop 131 ---------+ 
18:22:27 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:27 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:27 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:27 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:27 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:27 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:27 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s' 
18:22:28 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:28 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:28 [INFO] +- While loop 461 ---------+ 
18:22:28 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:28 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:28 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:28 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:28 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:28 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:28 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:29 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:29 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:29 [INFO] +- While loop 132 ---------+ 
18:22:29 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:29 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:29 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:29 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:29 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:29 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:29 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s' 
18:22:30 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:30 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:30 [INFO] +- While loop 462 ---------+ 
18:22:30 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:30 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:30 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:30 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:30 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:30 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:30 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:31 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:31 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:31 [INFO] +- While loop 133 ---------+ 
18:22:31 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:31 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:31 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:31 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:31 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:31 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:31 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s' 
18:22:32 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:32 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:32 [INFO] +- While loop 463 ---------+ 
18:22:32 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:32 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:32 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:32 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:32 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:32 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:32 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:32 [INFO]  Starting InstantQueue 'EXCOMMAND_FinancingEpDisorder'... 
18:22:32 [INFO]  Queue 'EXCOMMAND_FinancingEpDisorder' Executing: NARRATE 
                   "<proc[list_instances]>" 
18:22:32 [INFO] +- Executing dCommand: NARRATE/p@TheBlackCoyote ---------+ 
18:22:32 [INFO]  Filled tag <proc[list_instances]> with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:32 [INFO] +> Executing 'NARRATE': 
                   Narrating='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
                    Targets='[p@TheBlackCoyote]' 
18:22:32 [INFO]  Completing queue 'EXCOMMAND_FinancingEpDisorder' in 2ms. 
18:22:33 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:33 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:33 [INFO] +- While loop 134 ---------+ 
18:22:33 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:33 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:33 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:33 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:33 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:33 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:33 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s' 
18:22:34 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WHILE 
                   " CALLBACK" 
18:22:34 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:34 [INFO] +- While loop 464 ---------+ 
18:22:34 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:34 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:34 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:34 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:34 [INFO]  Queue 'EXCOMMAND_MichaelSpecificsTracks' Executing: WAIT "2s" 
18:22:34 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:34 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_MichaelSpecificsTracks'  
                   delay='d@2.0s' 
18:22:35 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WHILE 
                   " CALLBACK" 
18:22:35 [INFO]  Filled tag <p@theblackcoyote.is_online> with 'true'. 
18:22:35 [INFO] +- While loop 135 ---------+ 
18:22:35 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: ADJUST 
                   "p@theblackcoyote" 
                   "action_bar:<server.list_worlds.filter[contains[instance]]>" 
18:22:35 [INFO] +- Executing dCommand: ADJUST ---------+ 
18:22:35 [INFO]  Filled tag <server.list_worlds.filter[contains[instance]]> 
                   with 
                   'li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3'. 
18:22:35 [INFO] +> Executing 'ADJUST': object='p@theblackcoyote'  
                   mechanism='action_bar'  
                   mechanism_value='li@w@instancing/instances/a17e5bdb-9dc7-4ab5-a9f3-b5e587b77df3' 
18:22:35 [INFO]  Queue 'EXCOMMAND_CourtsStarterFunded' Executing: WAIT "2s" 
18:22:35 [INFO] +- Executing dCommand: WAIT ---------+ 
18:22:35 [INFO] +> Executing 'WAIT': queue='EXCOMMAND_CourtsStarterFunded'  
                   delay='d@2.0s'