Paste #18025: Denizen Debug Logs From A Test Server

Date: 2015/07/14 08:04:06 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link


Java Version: 1.8.0_45
Up-time: 2h 54m
CraftBukkit Version: git-Spigot-3ccbe77-a6a57a9 (MC: 1.8.7)
Denizen Version: Core: 1.10 (Build Unknown), CraftBukkit: 0.9.7-SNAPSHOT (build 287)
Active Plugins (8): AsyncWorldEditInjector: 2.1.3, Citizens: 2.0.16-SNAPSHOT (build 1240), WorldEdit: 6.0.2-SNAPSHOT;3370-58aac973, 
AsyncWorldEdit: 2.1.5, Denizen: 0.9.7-SNAPSHOT (build 287), WorldGuard: 6.0.0-SNAPSHOT.1592-, 
Webizen: 0.2.0, Depenizen: 0.2.1 (build 250)
Loaded Worlds (5): world, world_nether, world_the_end, guildworld, dungeonworld
Online Players (1): TheBlackCoyote(TheBlackCoyote)
Offline Players: 48
Mode: online

09:03:34 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886212858 does not exist! 
09:03:35 [INFO] +> [dChunk] valueOf dChunk unable to handle malformed format: 
                   ch@p@4c570928-3648-4583-9148-ee5ca157e175 
09:03:35 [INFO]  ERROR! 'p@4c570928-3648-4583-9148-ee5ca157e175' is an invalid 
                   dChunk! 
09:03:35 [INFO] +> [dChunk] valueOf dChunk unable to handle malformed format: 
                   ch@p@4c570928-3648-4583-9148-ee5ca157e175 
09:03:35 [INFO]  ERROR! 'p@4c570928-3648-4583-9148-ee5ca157e175' is an invalid 
                   dChunk! 
09:03:35 [INFO]  ERROR in script 'UNLOAD_LOOP'! Tag 
                   <def[value].replace[_].with[,].as_chunk.is_loaded> is invalid! 
09:03:37 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886214950 does not exist! 
09:03:39 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886217054 does not exist! 
09:03:41 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886219157 does not exist! 
09:03:43 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886221262 does not exist! 
09:03:45 [INFO] +> [dChunk] valueOf dChunk unable to handle malformed format: 
                   ch@quest,registry 
09:03:45 [INFO]  ERROR! 'QUEST,REGISTRY' is an invalid dChunk! 
09:03:45 [INFO] +> [dChunk] valueOf dChunk unable to handle malformed format: 
                   ch@quest,registry 
09:03:45 [INFO]  ERROR! 'QUEST,REGISTRY' is an invalid dChunk! 
09:03:45 [INFO]  ERROR in script 'UNLOAD_LOOP'! Tag 
                   <def[value].replace[_].with[,].as_chunk.is_loaded> is invalid! 
09:03:45 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886223364 does not exist! 
09:03:47 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886225466 does not exist! 
09:03:49 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886227570 does not exist! 
09:03:51 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886229674 does not exist! 
09:03:53 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886231779 does not exist! 
09:03:56 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886233882 does not exist! 
09:03:58 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886235986 does not exist! 
09:04:00 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886238092 does not exist! 
09:04:01 [INFO] +> Executing 'Event': Type='on time changes in world'  
                   Container='LOG_ONLINE_TIME(WORLD)'  Context='{world=w@world, 
                   time=23}' 
09:04:01 [INFO] +- Building event 'ON TIME CHANGES IN WORLD' for 
                   LOG_ONLINE_TIME ---------+ 
09:04:01 [INFO]  Starting InstantQueue 
                   'LOG_ONLINE_TIME_XmlBedroomsWritings'... 
09:04:01 [INFO]  Queue 'LOG_ONLINE_TIME_XmlBedroomsWritings' Executing: 
                   FOREACH "<server.list_online_players>" "{" "-" "yaml" 
                   "ID:%value%" "set" 
                   "%value%.general.online_time:+:<server.current_time_millis.sub[<def[value].flag[online_timer]>]>" 
                   "-" "flag" "%value%" 
                   "online_timer:<server.current_time_millis>" "}" 
09:04:01 [INFO] +- Executing dCommand: FOREACH ---------+ 
09:04:01 [INFO]  Filled tag <server.list_online_players> with 
                   'li@p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO] +> Executing 'FOREACH': 
                   list='li@p@4c570928-3648-4583-9148-ee5ca157e175' 
09:04:01 [INFO]  Queue 'LOG_ONLINE_TIME_XmlBedroomsWritings' Executing: YAML 
                   "ID:%value%" "set" 
                   "%value%.general.online_time:+:<server.current_time_millis.sub[<def[value].flag[online_timer]>]>" 
09:04:01 [INFO] +- Executing dCommand: YAML ---------+ 
09:04:01 [INFO]  Filled definition %value% with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO]  Filled definition %value% with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO]  Filled tag <def[value].flag[online_timer]> with 
                   '1436886190868'. 
09:04:01 [INFO]  Filled tag <server.current_time_millis.sub[1436886190868]> 
                   with '50180'. 
09:04:01 [INFO] +> Executing 'YAML': 
                   id='p@4c570928-3648-4583-9148-ee5ca157e175'  action='SET'  
                   yaml_action='INCREASE'  
                   key='p@4c570928-3648-4583-9148-ee5ca157e175.general.online_time' 
                    value='50180'  fix_formatting='false' 
09:04:01 [INFO]  Queue 'LOG_ONLINE_TIME_XmlBedroomsWritings' Executing: FLAG 
                   "%value%" "online_timer:<server.current_time_millis>" 
09:04:01 [INFO] +- Executing dCommand: FLAG ---------+ 
09:04:01 [INFO]  Filled definition %value% with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO]  Filled tag <server.current_time_millis> with '1436886241049'. 
09:04:01 [INFO] +> Executing 'FLAG': flag_name='ONLINE_TIMER'  
                   Action/Value='SET_VALUE(1436886241049)'  
                   flag_target='p@TheBlackCoyote' 
09:04:01 [INFO]  Queue 'LOG_ONLINE_TIME_XmlBedroomsWritings' Executing: 
                   FOREACH " CALLBACK" 
09:04:01 [INFO]  Completing queue 'LOG_ONLINE_TIME_XmlBedroomsWritings' in 
                   3ms. 
09:04:01 [INFO] +> Executing 'Event': Type='on time changes in world'  
                   Container='ANTI_AFKING(WORLD)'  Context='{world=w@world, 
                   time=23}' 
09:04:01 [INFO] +- Building event 'ON TIME CHANGES IN WORLD' for ANTI_AFKING 
                   ---------+ 
09:04:01 [INFO]  Starting InstantQueue 'ANTI_AFKING_TermYardsGoals'... 
09:04:01 [INFO]  Queue 'ANTI_AFKING_TermYardsGoals' Executing: FOREACH 
                   "<server.list_online_players>" "{" "-" "if" 
                   "<def[value].flag[last_location]||none>" "==" 
                   "<def[value].location>" "{" "-" "flag" "%value%" 
                   "autokick_strike:++" "-" "if" 
                   "<def[value].flag[autokick_strike]||none>" ">" "8" "{" "-" 
                   "if" "!<def[value].is_op>" "adjust" "%value%" "kick:You've 
                   been disconnected for idling." "-" "flag" "%value%" 
                   "autokick_strike:!" "}" "}" "else" "if" 
                   "<def[value].has_flag[autokick_strike]>" "{" "-" "flag" 
                   "%value%" "autokick_st... * snip! * 
09:04:01 [INFO] +- Executing dCommand: FOREACH ---------+ 
09:04:01 [INFO]  Filled tag <server.list_online_players> with 
                   'li@p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO] +> Executing 'FOREACH': 
                   list='li@p@4c570928-3648-4583-9148-ee5ca157e175' 
09:04:01 [INFO]  Queue 'ANTI_AFKING_TermYardsGoals' Executing: IF 
                   "<def[value].flag[last_location]||none>" "==" 
                   "<def[value].location>" "{" "-" "flag" "%value%" 
                   "autokick_strike:++" "-" "if" 
                   "<def[value].flag[autokick_strike]||none>" ">" "8" "{" "-" 
                   "if" "!<def[value].is_op>" "adjust" "%value%" "kick:You've 
                   been disconnected for idling." "-" "flag" "%value%" 
                   "autokick_strike:!" "}" "}" "else" "if" 
                   "<def[value].has_flag[autokick_strike]>" "{" "-" "flag" 
                   "%value%" "autokick_strike:!" "}" 
09:04:01 [INFO] +- Executing dCommand: IF ---------+ 
09:04:01 [INFO] +> Executing 'IF': use_braces='true' 
09:04:01 [INFO]  Filled tag <def[value].flag[last_location]||none> with 
                   'l@192.2888271456303,7.736926518764634,289.2586310125108,23.70606,185.40448,world'. 
09:04:01 [INFO]  Filled tag <def[value].location> with 
                   'l@192.2888271456303,7.736926518764634,289.2586310125108,23.017887,188.61597,world'. 
09:04:01 [INFO]  
                   Comparable='Element(l@192.2888271456303,7.736926518764634,289.2586310125108,23.70606,185.40448,world)', 
                   Operator='EQUALS', 
                   ComparedTo='Element(l@192.2888271456303,7.736926518764634,289.2586310125108,23.017887,188.61597,world)' 
                   --> OUTCOME='false' 
09:04:01 [INFO]  Filled tag <def[value].has_flag[autokick_strike]> with 
                   'false'. 
09:04:01 [INFO]  Queue 'ANTI_AFKING_TermYardsGoals' Executing: FLAG "%value%" 
                   "last_location:<def[value].location>" 
09:04:01 [INFO] +- Executing dCommand: FLAG ---------+ 
09:04:01 [INFO]  Filled definition %value% with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
09:04:01 [INFO]  Filled tag <def[value].location> with 
                   'l@192.2888271456303,7.736926518764634,289.2586310125108,23.017887,188.61597,world'. 
09:04:01 [INFO] +> Executing 'FLAG': flag_name='LAST_LOCATION'  
                   Action/Value='SET_VALUE(l@192.2888271456303,7.736926518764634,289.2586310125108,23.017887,188.61597,world)' 
                    flag_target='p@TheBlackCoyote' 
09:04:01 [INFO]  Queue 'ANTI_AFKING_TermYardsGoals' Executing: FOREACH 
                   " CALLBACK" 
09:04:01 [INFO]  Completing queue 'ANTI_AFKING_TermYardsGoals' in 3ms. 
09:04:01 [INFO] +> Executing 'Event': Type='on time changes in world'  
                   Container='NPCWANDERINGSTART(WORLD)'  Context='{world=w@world, 
                   time=23}' 
09:04:01 [INFO] +- Building event 'ON TIME CHANGES IN WORLD' for 
                   NPCWANDERINGSTART ---------+ 
09:04:01 [INFO]  Starting InstantQueue 'NPCWANDERINGSTART_MaiBanTeeth'... 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: ANNOUNCE 
                   "to_ops" "<&7>Wanderer cycle" 
09:04:01 [INFO] +- Executing dCommand: ANNOUNCE ---------+ 
09:04:01 [INFO]  Filled tag <&7> with ''. 
09:04:01 [INFO] +> Executing 'ANNOUNCE': Message='Wanderer cycle'  
                   Type='TO_OPS' 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: FOREACH 
                   "<server.flag[npcwanderers].as_list>" "{" "-" "if" 
                   "!<def[value].exists>" "{" "-" "flag" "server" 
                   "npcwanderers:<-:%value%" "-" "foreach" "next" "}" "-" 
                   "define" "target" 
                   "<def[value].location.find.surface_blocks.within[5].random>" 
                   "-" "announce" "to_ops" "moving %value% to %target%" "-" "if" 
                   "!<def[value].is_engaged>" "walk" "<def[target].above>" 
                   "speed:<util.random.int[0].to[2]>,<util.random.int[0].to[9]>" 
                   "}" 
09:04:01 [INFO] +- Executing dCommand: FOREACH ---------+ 
09:04:01 [INFO]  Filled tag <server.flag[npcwanderers].as_list> with 
                   'li@n@24'. 
09:04:01 [INFO] +> Executing 'FOREACH': list='li@n@24' 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: IF 
                   "!<def[value].exists>" "{" "-" "flag" "server" 
                   "npcwanderers:<-:%value%" "-" "foreach" "next" "}" 
09:04:01 [INFO] +- Executing dCommand: IF ---------+ 
09:04:01 [INFO] +> Executing 'IF': use_braces='true' 
09:04:01 [INFO]  Filled tag <def[value].exists> with 'true'. 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: DEFINE 
                   "target" 
                   "<def[value].location.find.surface_blocks.within[5].random>" 
09:04:01 [INFO] +- Executing dCommand: DEFINE ---------+ 
09:04:01 [INFO]  Filled tag 
                   <def[value].location.find.surface_blocks.within[5].random> 
                   with 'l@195.5,6.0,289.5,world'. 
09:04:01 [INFO] +> Executing 'DEFINE': queue='NPCWANDERINGSTART_MaiBanTeeth'  
                   definition='target'  value='l@195.5,6.0,289.5,world' 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: ANNOUNCE 
                   "to_ops" "moving %value% to %target%" 
09:04:01 [INFO] +- Executing dCommand: ANNOUNCE ---------+ 
09:04:01 [INFO]  Filled definition %value% with 'n@24'. 
09:04:01 [INFO]  Filled definition %target% with 'l@195.5,6.0,289.5,world'. 
09:04:01 [INFO] +> Executing 'ANNOUNCE': Message='moving n@24 to 
                   l@195.5,6.0,289.5,world'  Type='TO_OPS' 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: IF 
                   "!<def[value].is_engaged>" "walk" "<def[target].above>" 
                   "speed:<util.random.int[0].to[2]>,<util.random.int[0].to[9]>" 
09:04:01 [INFO] +- Executing dCommand: IF ---------+ 
09:04:01 [INFO] +> Executing 'IF': use_braces='false' 
09:04:01 [INFO]  Filled tag <def[value].is_engaged> with 'false'. 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: WALK 
                   "<def[target].above>" 
                   "speed:<util.random.int[0].to[2]>,<util.random.int[0].to[9]>" 
09:04:01 [INFO] +- Executing dCommand: WALK ---------+ 
09:04:01 [INFO]  Filled tag <def[target].above> with 
                   'l@195.5,7.0,289.5,world'. 
09:04:01 [INFO]  Filled tag <util.random.int[0].to[2]> with '2'. 
09:04:01 [INFO]  Filled tag <util.random.int[0].to[9]> with '1'. 
09:04:01 [INFO]  ERROR! 'speed:2,1' is an unknown argument! 
09:04:01 [INFO]  ERROR in script 'NPCWANDERINGSTART'! Woah! Invalid arguments 
                   were specified! 
09:04:01 [INFO] +> [Denizen] +> MESSAGE follows: 'Must have a valid spawned 
                   NPC attached.' 
09:04:01 [INFO] +> [Denizen] Usage: walk (<entity>|...) [<location>/stop] 
                   (speed:<#>) (auto_range) (radius:<#.#>) 
09:04:01 [INFO] +> [Denizen] (Attempted: WALK "<def[target].above>" 
                   "speed:<util.random.int[0].to[2]>,<util.random.int[0].to[9]>") 
09:04:01 [INFO] +---------------------+ 
09:04:01 [INFO]  Queue 'NPCWANDERINGSTART_MaiBanTeeth' Executing: FOREACH 
                   " CALLBACK" 
09:04:01 [INFO]  Completing queue 'NPCWANDERINGSTART_MaiBanTeeth' in 5ms. 
09:04:01 [INFO] +> Executing 'Event': Type='on time changes in world'  
                   Container='RANDOM_INITIATOR(WORLD)'  Context='{world=w@world, 
                   time=23}' 
09:04:01 [INFO] +- Building event 'ON TIME CHANGES IN WORLD' for 
                   RANDOM_INITIATOR ---------+ 
09:04:01 [INFO]  Starting InstantQueue 
                   'RANDOM_INITIATOR_TableOnceConcerned'... 
09:04:01 [INFO]  Queue 'RANDOM_INITIATOR_TableOnceConcerned' Executing: IF 
                   "<server.list_online_players.size>" "<" "1" "queue" "clear" 
09:04:01 [INFO] +- Executing dCommand: IF ---------+ 
09:04:01 [INFO] +> Executing 'IF': use_braces='false' 
09:04:01 [INFO]  Filled tag <server.list_online_players.size> with '1'. 
09:04:01 [INFO]  Comparable='Number(1)', Operator='LESS', 
                   ComparedTo='Number(1)' --> OUTCOME='false' 
09:04:01 [INFO]  Queue 'RANDOM_INITIATOR_TableOnceConcerned' Executing: IF 
                   "<util.random.int[1].to[64]>" "!=" "1" "queue" "clear" 
09:04:01 [INFO] +- Executing dCommand: IF ---------+ 
09:04:01 [INFO] +> Executing 'IF': use_braces='false' 
09:04:01 [INFO]  Filled tag <util.random.int[1].to[64]> with '52'. 
09:04:01 [INFO]  Logic='NEGATIVE', Comparable='Number(52)', Operator='EQUALS', 
                   ComparedTo='Number(1)' --> OUTCOME='true' 
09:04:01 [INFO]  Queue 'RANDOM_INITIATOR_TableOnceConcerned' Executing: QUEUE 
                   "clear" 
09:04:01 [INFO] +- Executing dCommand: QUEUE ---------+ 
09:04:01 [INFO] +> Executing 'QUEUE': 
                   queue='q@RANDOM_INITIATOR_TableOnceConcerned'  Action='CLEAR' 
09:04:01 [INFO]  Completing queue 'RANDOM_INITIATOR_TableOnceConcerned' in 
                   1ms. 
09:04:02 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1436886240195 does not exist!