Paste #15587: Denizen Debug Logs From A Test Server

Date: 2015/05/05 16:45:12 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link


Java Version: 1.7.0_51
Up-time: 58m
CraftBukkit Version: git-Spigot-2ec6f06-7722428 (MC: 1.8.3)
Denizen Version: Core: 1.05 (Build Unknown), CraftBukkit: 0.9.6-SNAPSHOT (build 1574)
Active Plugins (13): Citizens: 2.0.15-SNAPSHOT (build 1228), AsyncWorldEditInjector: 2.1.3, WorldEdit: 6.0.2-SNAPSHOT;3370-58aac973, 
Denizen: 0.9.6-SNAPSHOT (build 1574), Prism: 2.0.6-35, WorldGuard: 6.0.0-SNAPSHOT.1592-, 
RemoteToolkitPlugin: R10_A15.3, Essentials: TeamCity, Depenizen: 0.2.1 (build 245), 
WorldEditSelectionVisualizer: 1.4.2, Webizen: ${project.version} (build ${BUILD_NUMBER}), 
EssentialsChat: TeamCity, AsyncWorldEdit: 2.1.5
Loaded Worlds (7): world, world_nether, world_the_end, lotr, LOTR MAP, world1, doesntreallymatter
Online Players (1): TheBlackCoyote(TheBlackCoyote)
Offline Players: 8
Mode: online

19:45:04 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1430869502419 does not exist! 
19:45:05 [INFO] +> Executing 'Event': Type='on player breaks block'  
                   Container='SQL_MANAGING(WORLD)'  
                   player=p@TheBlackCoyoteContext='{cuboids=li@, 
                   location=l@-358.0,3.0,108.0,world, xp=0, material=m@dirt}' 
19:45:05 [INFO] +- Building event 'ON PLAYER BREAKS BLOCK' for SQL_MANAGING 
                   ---------+ 
19:45:05 [INFO]  Starting InstantQueue 
                   'SQL_MANAGING_SituationCateringRating'... 
19:45:05 [INFO]  Queue 'SQL_MANAGING_SituationCateringRating' Executing: SQL 
                   "id:DE" "query:SELECT * FROM block_tracking WHERE 
                   location='<context.location.simple>';" "save:return" 
19:45:05 [INFO] +- Executing dCommand: SQL/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  ...remembering this script entry as 'return'! 
19:45:05 [INFO]  Filled tag <context.location.simple> with '-358,3,108,world'. 
19:45:05 [INFO] +> Executing 'SQL': sqlid='DE'  action='QUERY'  query='SELECT 
                   * FROM block_tracking WHERE location='-358,3,108,world';' 
19:45:05 [INFO]  Running query SELECT * FROM block_tracking WHERE 
                   location='-358,3,108,world'; 
19:45:05 [INFO]  Got a query result of 2 columns 
19:45:05 [INFO]  Got a query result of 1 rows 
19:45:05 [INFO]  Queue 'SQL_MANAGING_SituationCateringRating' Executing: IF 
                   "<entry[return].result>" "contains" 
                   "<context.location.simple>" "{" "-" "sql" "id:DE" 
                   "update:UPDATE block_tracking SET cause='player' where 
                   location='<context.location.simple>';" "}" "else" "{" "-" 
                   "sql" "id:DE" "update:INSERT INTO block_tracking VALUES 
                   ('<context.location.simple>','player');" "}" 
19:45:05 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'IF': use_braces='true' 
19:45:05 [INFO]  Filled tag <entry[return].result> with 
                   'li@-358,3,108,world/player/'. 
19:45:05 [INFO]  Filled tag <context.location.simple> with '-358,3,108,world'. 
19:45:05 [INFO]  Comparable='dList(li@-358,3,108,world/player/)', 
                   Operator='CONTAINS', ComparedTo='Element(-358,3,108,world)' 
                   --> OUTCOME='false' 
19:45:05 [INFO]  Queue 'SQL_MANAGING_SituationCateringRating' Executing: SQL 
                   "id:DE" "update:INSERT INTO block_tracking VALUES 
                   ('<context.location.simple>','player');" 
19:45:05 [INFO] +- Executing dCommand: SQL/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <context.location.simple> with '-358,3,108,world'. 
19:45:05 [INFO] +> Executing 'SQL': sqlid='DE'  action='UPDATE'  query='INSERT 
                   INTO block_tracking VALUES ('-358,3,108,world','player');' 
19:45:05 [INFO]  Running update INSERT INTO block_tracking VALUES 
                   ('-358,3,108,world','player'); 
19:45:05 [INFO]  Got a query result of 1 columns 
19:45:05 [INFO]  Updated 1 rows 
19:45:05 [INFO]  Queue 'SQL_MANAGING_SituationCateringRating' Executing: WAIT 
                   "2s" 
19:45:05 [INFO] +- Executing dCommand: WAIT/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'WAIT': 
                   queue='SQL_MANAGING_SituationCateringRating'  delay='d@40t' 
19:45:05 [INFO]  Forcing queue SQL_MANAGING_SituationCateringRating into a 
                   timed queue... 
19:45:05 [INFO]  Completing queue 'SQL_MANAGING_SituationCateringRating' in 
                   49ms. 
19:45:05 [INFO]  Starting TimedQueue 'SQL_MANAGING_SituationCateringRating'... 
19:45:05 [INFO] +> Executing 'Event': Type='on player breaks dirt'  
                   Container='EXPERIENCE_GAINS_EXCAVATING(WORLD)'  
                   player=p@TheBlackCoyoteContext='{cuboids=li@, 
                   location=l@-358.0,3.0,108.0,world, xp=0, material=m@dirt}' 
19:45:05 [INFO] +- Building event 'ON PLAYER BREAKS DIRT' for 
                   EXPERIENCE_GAINS_EXCAVATING ---------+ 
19:45:05 [INFO]  Starting InstantQueue 
                   'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical'... 
19:45:05 [INFO]  Queue 'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' 
                   Executing: IF "<context.location.prism_logs.as_string||none>" 
                   "contains" "player" "define" "effect" "reduce" 
19:45:05 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'IF': use_braces='false' 
19:45:05 [INFO]  Filled tag <context.location.prism_logs.as_string||none> with 
                   'prism@block-place[player=p@TheBlackCoyote;location=l@-358,3,108,world;block=3,0/0,0;aggregate=1] 
                   prism@block-break[player=p@TheBlackCoyote;location=l@-358,3,108,world;block=2,0/0,0;aggregate=1]'. 
19:45:05 [INFO]  
                   Comparable='Element(prism@block-place[player=p@TheBlackCoyote;location=l@-358,3,108,world;block=3,0/0,0;aggregate=1] 
                   prism@block-break[player=p@TheBlackCoyote;location=l@-358,3,108,world;block=2,0/0,0;aggregate=1])', 
                   Operator='CONTAINS', ComparedTo='Element(player)' --> 
                   OUTCOME='true' 
19:45:05 [INFO]  Queue 'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' 
                   Executing: DEFINE "effect" "reduce" 
19:45:05 [INFO] +- Executing dCommand: DEFINE/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'DEFINE': 
                   queue='EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical'  
                   definition='effect'  value='reduce' 
19:45:05 [INFO]  Queue 'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' 
                   Executing: WAIT "1t" 
19:45:05 [INFO] +- Executing dCommand: WAIT/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'WAIT': 
                   queue='EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical'  
                   delay='d@1t' 
19:45:05 [INFO]  Forcing queue 
                   EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical into a 
                   timed queue... 
19:45:05 [INFO]  Completing queue 
                   'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' in 19ms. 
19:45:05 [INFO]  Starting TimedQueue 
                   'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical'... 
19:45:05 [INFO]  Queue 'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' 
                   Executing: RUN "give_exp" 
                   "def:excavating|10|<def[effect]||none>" "instantly" 
19:45:05 [INFO] +- Executing dCommand: RUN/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <def[effect]||none> with 'reduce'. 
19:45:05 [INFO] +> Executing 'RUN': script='GIVE_EXP(TASK)'  instant='true'  
                   definitions='li@excavating|10|reduce' 
19:45:05 [INFO]  Adding definition %1% as excavating 
19:45:05 [INFO]  Adding definition %2% as 10 
19:45:05 [INFO]  Adding definition %3% as reduce 
19:45:05 [INFO]  Starting InstantQueue 'GIVE_EXP_VgValuedCheque'... 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: IF "<def[3]>" "==" 
                   "reduce" "{" "-" "define" "2" "<def[2].div[100].round_up>" "}" 
19:45:05 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'IF': use_braces='true' 
19:45:05 [INFO]  Filled tag <def[3]> with 'reduce'. 
19:45:05 [INFO]  Comparable='Element(reduce)', Operator='EQUALS', 
                   ComparedTo='Element(reduce)' --> OUTCOME='true' 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: DEFINE "2" 
                   "<def[2].div[100].round_up>" 
19:45:05 [INFO] +- Executing dCommand: DEFINE/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <def[2].div[100].round_up> with '1'. 
19:45:05 [INFO] +> Executing 'DEFINE': queue='GIVE_EXP_VgValuedCheque'  
                   definition='2'  value='1' 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: FLAG "<player>" 
                   "<def[1]>_exp:+:<def[2]>" 
19:45:05 [INFO] +- Executing dCommand: FLAG/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <def[2]> with '1'. 
19:45:05 [INFO] +> Executing 'FLAG': flag_name='EXCAVATING_EXP'  
                   Action/Value='INCREASE(1)'  flag_target='p@TheBlackCoyote' 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: TITLE 
                   "subtitle:<&f>                            
                   <&7>+<player.flag[exp_drop].add[<def[2]>].as_int||<def[2]>> 
                   xp" "targets:<player>" "fade_in:1t" "stay:10t" "fade_out:2s" 
19:45:05 [INFO] +- Executing dCommand: TITLE/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <&f> with ''. 
19:45:05 [INFO]  Filled tag <&7> with ''. 
19:45:05 [INFO]  Filled tag <def[2]> with '1'. 
19:45:05 [INFO]  Filled tag <player.flag[exp_drop].add[<def[2]>].as_int||1> 
                   with '1'. 
19:45:05 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:05 [INFO] +> Executing 'TITLE': subtitle='                            +1 
                   xp'  fade_in='d@1t'  stay='d@10t'  fade_out='d@40t'  
                   targets='[p@4c570928-3648-4583-9148-ee5ca157e175]' 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: FLAG "<player>" 
                   "exp_drop:+:<def[2]>" "duration:2s" 
19:45:05 [INFO] +- Executing dCommand: FLAG/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:05 [INFO]  Filled tag <def[2]> with '1'. 
19:45:05 [INFO] +> Executing 'FLAG': flag_name='EXP_DROP'  
                   Action/Value='INCREASE(1)'  duration='d@40t'  
                   flag_target='p@TheBlackCoyote' 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: RUN "check_level" 
                   "def:<def[1]>" "instantly" 
19:45:05 [INFO] +- Executing dCommand: RUN/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO] +> Executing 'RUN': script='CHECK_LEVEL(TASK)'  instant='true' 
                    definitions='li@excavating' 
19:45:05 [INFO]  Adding definition %1% as excavating 
19:45:05 [INFO]  Starting InstantQueue 'CHECK_LEVEL_PoorLebanonTrinity'... 
19:45:05 [INFO]  Queue 'CHECK_LEVEL_PoorLebanonTrinity' Executing: IF 
                   "<player.flag[<def[1]>_exp]>" ">=" 
                   "<player.flag[<def[1]>_level].power[2.6].mul[84]||1>" "run" 
                   "level_up" "def:<def[1]>" "instantly" 
19:45:05 [INFO] +- Executing dCommand: IF/p@TheBlackCoyote ---------+ 
19:45:05 [INFO] +> Executing 'IF': use_braces='false' 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <player.flag[excavating_exp]> with '38793.0'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag 
                   <player.flag[excavating_level].power[2.6].mul[84]||1> with 
                   '42845.01186874608252'. 
19:45:05 [INFO]  Comparable='Decimal(38793.0)', Operator='OR_MORE', 
                   ComparedTo='Decimal(42845.011868746085)' --> OUTCOME='false' 
19:45:05 [INFO]  Completing queue 'CHECK_LEVEL_PoorLebanonTrinity' in 4ms. 
19:45:05 [INFO]  Queue 'GIVE_EXP_VgValuedCheque' Executing: RUN 
                   "show_exp_boss_bar" "def:<def[1]>" 
19:45:05 [INFO] +- Executing dCommand: RUN/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO] +> Executing 'RUN': script='SHOW_EXP_BOSS_BAR(TASK)'  
                   definitions='li@excavating' 
19:45:05 [INFO]  Adding definition %1% as excavating 
19:45:05 [INFO]  Starting TimedQueue 
                   'SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers'... 
19:45:05 [INFO]  Queue 'SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers' 
                   Executing: FLAG "<player>" "show_boss_bar:true" "duration:5s" 
19:45:05 [INFO] +- Executing dCommand: FLAG/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:05 [INFO] +> Executing 'FLAG': flag_name='SHOW_BOSS_BAR'  
                   Action/Value='SET_VALUE(true)'  duration='d@100t'  
                   flag_target='p@TheBlackCoyote' 
19:45:05 [INFO]  Completing queue 'GIVE_EXP_VgValuedCheque' in 29ms. 
19:45:05 [INFO]  Completing queue 
                   'EXPERIENCE_GAINS_EXCAVATING_PathsInnsPharmaceutical' in 94ms. 
19:45:05 [INFO]  Queue 'SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers' 
                   Executing: ADJUST "<player>" 
                   "show_boss_bar:<player.flag[<def[1]>_exp].sub[<player.flag[<def[1]>_level].sub[1].power[2.6].mul[84]||1>].div[<player.flag[<def[1]>_level].power[2.6].mul[84].sub[<player.flag[<def[1]>_level].sub[1].power[2.6].mul[84]||1>]||1>].mul[200].as_int>|<&d><player.flag[<def[1]>_level].as_int||0> 
                            <def[1]> progress          
                   <player.flag[<def[1]>_level].add[1].as_int||0>" 
19:45:05 [INFO] +- Executing dCommand: ADJUST/p@TheBlackCoyote ---------+ 
19:45:05 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag 
                   <player.flag[excavating_level].sub[1].power[2.6].mul[84]||1> 
                   with '33441.0023264937732'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag 
                   <player.flag[excavating_level].sub[1].power[2.6].mul[84]||1> 
                   with '33441.0023264937732'. 
19:45:05 [INFO]  Filled tag 
                   <player.flag[excavating_level].power[2.6].mul[84].sub[33441.0023264937732]||1> 
                   with '9404.00954225230932'. 
19:45:05 [INFO]  Filled tag 
                   <player.flag[excavating_exp].sub[33441.0023264937732].div[9404.00954225230932].mul[200].as_int> 
                   with '114'. 
19:45:05 [INFO]  Filled tag <&d> with ''. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <player.flag[excavating_level].as_int||0> with 
                   '11'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:05 [INFO]  Filled tag <player.flag[excavating_level].add[1].as_int||0> 
                   with '12'. 
19:45:05 [INFO] +> Executing 'ADJUST': 
                   object='p@4c570928-3648-4583-9148-ee5ca157e175'  
                   mechanism='show_boss_bar'  mechanism_value='114|11          
                   excavating progress          12' 
19:45:06 [INFO]  Queue 'SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers' 
                   Executing: ADJUST "<player>" 
                   "tab_list_info:<&nl><&d><player.flag[<def[1]>_exp].as_int>    
                   -    XP remaining<&co> 
                   <player.flag[<def[1]>_level].power[2.6].mul[84].sub[<player.flag[<def[1]>_exp]>].round_up> 
                      -    
                   <player.flag[<def[1]>_level].power[2.6].mul[84].as_int><&nl><&b>" 
19:45:06 [INFO] +- Executing dCommand: ADJUST/p@TheBlackCoyote ---------+ 
19:45:06 [INFO]  Filled tag <player> with 
                   'p@4c570928-3648-4583-9148-ee5ca157e175'. 
19:45:06 [INFO]  Filled tag <&nl> with '
'. 
19:45:06 [INFO]  Filled tag <&d> with ''. 
19:45:06 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:06 [INFO]  Filled tag <player.flag[excavating_exp].as_int> with '38793'. 
19:45:06 [INFO]  Filled tag <&co> with ':'. 
19:45:06 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:06 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:06 [INFO]  Filled tag <player.flag[excavating_exp]> with '38793.0'. 
19:45:06 [INFO]  Filled tag 
                   <player.flag[excavating_level].power[2.6].mul[84].sub[38793.0].round_up> 
                   with '4053'. 
19:45:06 [INFO]  Filled tag <def[1]> with 'excavating'. 
19:45:06 [INFO]  Filled tag 
                   <player.flag[excavating_level].power[2.6].mul[84].as_int> with 
                   '42845'. 
19:45:06 [INFO]  Filled tag <&nl> with '
'. 
19:45:06 [INFO]  Filled tag <&b> with ''. 
19:45:06 [INFO] +> Executing 'ADJUST': 
                   object='p@4c570928-3648-4583-9148-ee5ca157e175'  
                   mechanism='tab_list_info'  mechanism_value='
38793    -    XP 
                   remaining: 4053    -    42845
' 
19:45:06 [INFO]  ERROR in script 'ABILITY_COOLDOWN_VISUALIZER'! Scoreboard 
                   TheBlackCoyote1430869504527 does not exist! 
19:45:06 [INFO]  Queue 'SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers' 
                   Executing: WAIT "6s" 
19:45:06 [INFO] +- Executing dCommand: WAIT/p@TheBlackCoyote ---------+ 
19:45:06 [INFO] +> Executing 'WAIT': 
                   queue='SHOW_EXP_BOSS_BAR_StrategiesMinecraftVolunteers'  
                   delay='d@120t'