Paste #42254: Denizen Debug Logs From A Minecraft Server

Date: 2017/05/27 11:57:09 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link


Java Version: 1.8.0_131
Up-time: 2h 36m
CraftBukkit Version: git-Spigot-4741400-a1d3777 (MC: 1.11.2)
Denizen Version: Core: 1.15 (Build 79), CraftBukkit: 1.0.1-SNAPSHOT (build 1633)
Active Plugins (8): Citizens: 2.0.21-SNAPSHOT (build 1469), WorldEdit: 6.1.5;4651611, BetterShops: 2.1.2, 
Denizen: 1.0.1-SNAPSHOT (build 1633), MythicMobs: 2.5.11, SaneEconomy: 0.10.4, 
VoxelSniper: 5.171.0-SNAPSHOT, ItemsXL: 0.2.3
Loaded Worlds (2): buildings, buildings_the_end
Online Players (1): Kaiser_Radbot(Kaiser_Radbot)
Offline Players: 1
Mode: online

19:55:55 [INFO] +- Getting interact script: n@guideman/p@Kaiser_Radbot 
                   ---------+ 
19:55:55 [INFO]  OKAY! '10 GUIDE_INTERACT' meets requirements. 
19:55:55 [INFO]  
19:55:55 [INFO]  OKAY! Highest scoring script is GUIDE_INTERACT. 
19:55:55 [INFO] +> [InteractScri...] Current step for this script is: 1 
19:55:55 [INFO] +---------------------+ 
19:55:55 [INFO] +- Parsing CLICK trigger: n@guideman/p@Kaiser_Radbot 
                   ---------+ 
19:55:55 [INFO]  Starting TimedQueue 'GUIDE_INTERACT_CodFeelSimply'... 
19:55:55 [INFO]  Queue 'GUIDE_INTERACT_CodFeelSimply' Executing: NARRATE 
                   "format:cchat" "Hello! Would you like a short tour?" 
19:55:55 [INFO] +- Executing dCommand: NARRATE/p@Kaiser_Radbot ---------+ 
19:55:55 [INFO] +> Executing 'NARRATE': Narrating='Hello! Would you like a 
                   short tour?'  Targets='[p@Kaiser_Radbot]'  Format='CCHAT' 
19:55:55 [INFO]  Filled tag <&2> with ''. 
19:55:55 [INFO]  Filled tag <npc.name> with 'guideman'. 
19:55:55 [INFO]  Filled tag <&f> with ''. 
19:55:55 [INFO]  Filled tag <&co> with ':'. 
19:55:55 [INFO]  Filled tag <&6> with ''. 
19:55:55 [INFO]  Filled tag <el@val[Hello&exc Would you like a short 
                   tour?].unescaped> with 'Hello! Would you like a short tour?'. 
19:55:56 [INFO]  Queue 'GUIDE_INTERACT_CodFeelSimply' Executing: NARRATE "Say 
                   /yes/ or /no/" 
19:55:56 [INFO] +- Executing dCommand: NARRATE/p@Kaiser_Radbot ---------+ 
19:55:56 [INFO] +> Executing 'NARRATE': Narrating='Say /yes/ or /no/'  
                   Targets='[p@Kaiser_Radbot]' 
19:55:56 [INFO]  Completing queue 'GUIDE_INTERACT_CodFeelSimply' in 499ms. 
19:55:57 [INFO] +- Getting interact script: n@guideman/p@Kaiser_Radbot 
                   ---------+ 
19:55:57 [INFO]  OKAY! '10 GUIDE_INTERACT' meets requirements. 
19:55:57 [INFO]  
19:55:57 [INFO]  OKAY! Highest scoring script is GUIDE_INTERACT. 
19:55:57 [INFO] +> [InteractScri...] Current step for this script is: 1 
19:55:57 [INFO] +---------------------+ 
19:55:57 [INFO] +> Executing 'CHAT': Player='Kaiser_Radbot'  NPC='guideman/35' 
                    Radius(Max)='1.9683012564344842(6.0)'  Trigger text='yes'  
                   LOS='true'  Facing='true' 
19:55:57 [INFO]  Filled tag <&2> with ''. 
19:55:57 [INFO]  Filled tag <npc.name.nickname> with 'guideman'. 
19:55:57 [INFO]  Filled tag <&f> with ''. 
19:55:57 [INFO]  Filled tag <&a> with ''. 
19:55:57 [INFO]  Filled tag <&2> with ''. 
19:55:57 [INFO]  Filled tag <player.name> with 'Kaiser_Radbot'. 
19:55:57 [INFO]  Filled tag <npc.name.nickname> with 'guideman'. 
19:55:57 [INFO]  Filled tag <&f> with ''. 
19:55:57 [INFO]  Filled tag <&a> with ''. 
19:55:57 [INFO] +- Parsing CHAT trigger: n@guideman/p@Kaiser_Radbot ---------+ 
19:55:57 [INFO]  Starting TimedQueue 
                   'GUIDE_INTERACT_AcknowledgeRepresentativeMark'... 
19:55:57 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: NARRATE "format:cchat" "Great! I love tours!" 
19:55:57 [INFO] +- Executing dCommand: NARRATE/p@Kaiser_Radbot ---------+ 
19:55:57 [INFO] +> Executing 'NARRATE': Narrating='Great! I love tours!'  
                   Targets='[p@Kaiser_Radbot]'  Format='CCHAT' 
19:55:57 [INFO]  Filled tag <&2> with ''. 
19:55:57 [INFO]  Filled tag <npc.name> with 'guideman'. 
19:55:57 [INFO]  Filled tag <&f> with ''. 
19:55:57 [INFO]  Filled tag <&co> with ':'. 
19:55:57 [INFO]  Filled tag <&6> with ''. 
19:55:57 [INFO]  Filled tag <el@val[Great&exc I love tours&exc].unescaped> 
                   with 'Great! I love tours!'. 
19:55:57 [INFO] +> [] CHAT: Kaiser_Radbot: yes 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: CREATE "player" "Guide" "<npc.location>" 
                   "save:guide" 
19:55:58 [INFO] +- Executing dCommand: CREATE/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  ...remembering this script entry as 'guide'! 
19:55:58 [INFO]  Filled tag <npc.location> with 
                   'l@341.42324159660086,22.0,2112.4422932919865,0.0,66.60899,buildings'. 
19:55:58 [INFO] +> Executing 'CREATE': name='Guide'  entity_type='e@player'  
                   spawn_location='l@341.42324159660086,22.0,2112.4422932919865,0.0,66.60899,buildings' 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: ADJUST "<player>" "hide_entity:<npc>" 
19:55:58 [INFO] +- Executing dCommand: ADJUST/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  Filled tag <player> with 
                   'p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'. 
19:55:58 [INFO]  Filled tag <npc> with 'n@35'. 
19:55:58 [INFO] +> Executing 'ADJUST': 
                   object='p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'  
                   mechanism='hide_entity'  mechanism_value='n@35' 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: ADJUST 
                   "<server.list_online_players.exclude[<player>]>" 
                   "hide_entity:<entry[guide].created_npc>" 
19:55:58 [INFO] +- Executing dCommand: ADJUST/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  Filled tag <player> with 
                   'p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'. 
19:55:58 [INFO]  Filled tag 
                   <server.list_online_players.exclude[p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86]> 
                   with 'li@'. 
19:55:58 [INFO]  Filled tag <entry[guide].created_npc> with 'n@40'. 
19:55:58 [INFO] +> Executing 'ADJUST': object='li@'  mechanism='hide_entity'  
                   mechanism_value='n@40' 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: RUN "guide_path" "npc:<entry[guide].created_npc>" 
                   "def:<^npc>" 
19:55:58 [INFO] +- Executing dCommand: RUN/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  Filled tag <npc> with 'n@35'. 
19:55:58 [INFO]  ...replacing the linked NPC with <entry[guide].created_npc> 
19:55:58 [INFO]  Filled tag <entry[guide].created_npc> with 'n@40'. 
19:55:58 [INFO] +> Executing 'RUN': script='GUIDE_PATH(TASK)'  
                   definitions='n@35' 
19:55:58 [INFO]  Adding definition %masterguide% as n@35 
19:55:58 [INFO]  Starting TimedQueue 'GUIDE_PATH_HowtoReturningStorm'... 
19:55:58 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: WALK 
                   "<def[masterguide].anchor[spot1]>" "auto_range" 
19:55:58 [INFO] +- Executing dCommand: WALK/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  Filled tag <def[masterguide].anchor[spot1]> with 
                   'l@337.85592063622187,22.0,2103.752579738467,10.799664,-15.591004,buildings'. 
19:55:58 [INFO] +> Executing 'WALK': 
                   location='l@337.85592063622187,22.0,2103.752579738467,10.799664,-15.591004,buildings' 
                    auto_range='true'  stop='false'  entities='[n@40]' 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: FLAG "<player>" "completedtutorial" 
19:55:58 [INFO] +- Executing dCommand: FLAG/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO]  Filled tag <player> with 
                   'p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'. 
19:55:58 [INFO] +> Executing 'FLAG': flag_name='completedtutorial'  
                   Action/Value='SET_BOOLEAN(true)'  
                   flag_target='p@Kaiser_Radbot' 
19:55:58 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: INJECT 
                   "guide_talk_welcomeintrorules" 
19:55:58 [INFO] +- Executing dCommand: INJECT/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO] +> Executing 'INJECT': 
                   script='GUIDE_TALK_WELCOMEINTRORULES(TASK)' 
19:55:58 [INFO]  Queue 'GUIDE_INTERACT_AcknowledgeRepresentativeMark' 
                   Executing: ZAP "2" 
19:55:58 [INFO] +- Executing dCommand: ZAP/p@Kaiser_Radbot ---------+ 
19:55:58 [INFO] +> Executing 'ZAP': Player='p@Kaiser_Radbot'  
                   script='GUIDE_INTERACT(INTERACT)'  step='2' 
19:55:58 [INFO]  Completing queue 
                   'GUIDE_INTERACT_AcknowledgeRepresentativeMark' in 947ms. 
19:55:59 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: LOOKCLOSE 
                   "true" "<npc>" 
19:55:59 [INFO] +- Executing dCommand: LOOKCLOSE/p@Kaiser_Radbot ---------+ 
19:55:59 [INFO]  Filled tag <npc> with 'n@40'. 
19:55:59 [INFO] +> Executing 'LOOKCLOSE': npc='n@40'  realistic='null'  
                   range='null'  toggle='true' 
19:55:59 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: NARRATE 
                   "format:cchat" "Hey <player.name>, thanks for taking the time 
                   to come on this tour. The purpose is to outline the basic 
                   aspects of Eldin, and introduce you to individuals who can 
                   tell you more." 
19:55:59 [INFO] +- Executing dCommand: NARRATE/p@Kaiser_Radbot ---------+ 
19:55:59 [INFO]  Filled tag <player.name> with 'Kaiser_Radbot'. 
19:55:59 [INFO] +> Executing 'NARRATE': Narrating='Hey Kaiser_Radbot, thanks 
                   for taking the time to come on this tour. The purpose is to 
                   outline the basic aspects of Eldin, and introduce you to 
                   individuals who can tell you more.'  
                   Targets='[p@Kaiser_Radbot]'  Format='CCHAT' 
19:55:59 [INFO]  Filled tag <&2> with ''. 
19:55:59 [INFO]  Filled tag <npc.name> with 'Guide'. 
19:55:59 [INFO]  Filled tag <&f> with ''. 
19:55:59 [INFO]  Filled tag <&co> with ':'. 
19:55:59 [INFO]  Filled tag <&6> with ''. 
19:55:59 [INFO]  Filled tag <el@val[Hey Kaiser_Radbot, thanks for taking the 
                   time to come on this tour&dot The purpose is to outline the 
                   basic aspects of Eldin, and introduce you to individuals who 
                   can tell you more&dot].unescaped> with 'Hey Kaiser_Radbot, 
                   thanks for taking the time to come on this tour. The purpose 
                   is to outline the basic aspects of Eldin, and introduce you to 
                   individuals who can tell you more.'. 
19:55:59 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: WAIT "7" 
19:55:59 [INFO] +- Executing dCommand: WAIT/p@Kaiser_Radbot ---------+ 
19:55:59 [INFO] +> Executing 'WAIT': queue='GUIDE_PATH_HowtoReturningStorm'  
                   delay='d@7.0s' 
19:56:06 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: NARRATE 
                   "format:cchat" "Before we start, make sure not to use any 
                   cheats like x-ray, flying, auto-fishing, or any kind of hacked 
                   client, etc. Eldin is meant to be a challenging experience!" 
19:56:06 [INFO] +- Executing dCommand: NARRATE/p@Kaiser_Radbot ---------+ 
19:56:06 [INFO] +> Executing 'NARRATE': Narrating='Before we start, make sure 
                   not to use any cheats like x-ray, flying, auto-fishing, or any 
                   kind of hacked client, etc. Eldin is meant to be a challenging 
                   experience!'  Targets='[p@Kaiser_Radbot]'  Format='CCHAT' 
19:56:06 [INFO]  ERROR! Narrated to non-existent or offline player! 
19:56:06 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: WAIT "6" 
19:56:06 [INFO] +- Executing dCommand: WAIT/p@Kaiser_Radbot ---------+ 
19:56:06 [INFO] +> Executing 'WAIT': queue='GUIDE_PATH_HowtoReturningStorm'  
                   delay='d@6.0s' 
19:56:12 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: LOOKCLOSE 
                   "false" "<npc>" 
19:56:12 [INFO] +- Executing dCommand: LOOKCLOSE/p@Kaiser_Radbot ---------+ 
19:56:12 [INFO]  Filled tag <npc> with 'n@40'. 
19:56:12 [INFO] +> Executing 'LOOKCLOSE': npc='n@40'  realistic='null'  
                   range='null'  toggle='false' 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: INJECT 
                   "guide_trypause" 
19:56:13 [INFO] +- Executing dCommand: INJECT/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO] +> Executing 'INJECT': script='GUIDE_TRYPAUSE(TASK)' 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: IF 
                   "!<player.is_online>" "{" "-" "remove" "<npc>" "-" "adjust" 
                   "<player>" "show_entity:<def[masterguide]>" "-" "zap" "1" 
                   "script:s@guide_interact" "-" "queue" "clear" "}" "else" "{" 
                   "-" "^while" 
                   "<npc.location.distance[<player.location>].is[OR_MORE].than[5]>" 
                   "{" "-" "if" 
                   "<npc.location.distance[<player.location>].is[OR_MORE].than[30]>" 
                   "{" "-" "narrate" "format:cchat" "I see you don't want to 
                   complete the tutorial. Enjoy your time at Eldin!" "-" "remove" 
                   "... * snip! * 
19:56:13 [INFO] +- Executing dCommand: IF/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO] +> Executing 'IF': use_braces='true' 
19:56:13 [INFO]  Filled tag <player.is_online> with 'false'. 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: REMOVE 
                   "<npc>" 
19:56:13 [INFO] +- Executing dCommand: REMOVE/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO]  Filled tag <npc> with 'n@40'. 
19:56:13 [INFO] +> Executing 'REMOVE': entities='[n@40]' 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: ADJUST 
                   "<player>" "show_entity:<def[masterguide]>" 
19:56:13 [INFO] +- Executing dCommand: ADJUST/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO]  Filled tag <player> with 
                   'p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'. 
19:56:13 [INFO]  Filled tag <def[masterguide]> with 'n@35'. 
19:56:13 [INFO] +> Executing 'ADJUST': 
                   object='p@f5942fe1-15b7-42d2-8d6d-d7a2f8027d86'  
                   mechanism='show_entity'  mechanism_value='n@35' 
19:56:13 [INFO]  ERROR in script 'GUIDE_TRYPAUSE'! Woah!! An exception has 
                   been called with this command! 
19:56:13 [INFO]  ERROR in script 'GUIDE_TRYPAUSE'! Internal exception was 
                   thrown! 
19:56:13 [SEVERE] java.lang.NullPointerException
19:56:13 [SEVERE] net.aufdemrand.denizen.nms.helpers.EntityHelper_v1_11_R1.unhideEntity(EntityHelper_v1_11_R1.java:336)
19:56:13 [SEVERE] net.aufdemrand.denizen.objects.dPlayer.adjust(dPlayer.java:2593)
19:56:13 [SEVERE] net.aufdemrand.denizen.scripts.commands.core.AdjustCommand.execute(AdjustCommand.java:100)
19:56:13 [SEVERE] net.aufdemrand.denizencore.scripts.commands.CommandExecuter.execute(CommandExecuter.java:248)
19:56:13 [SEVERE] net.aufdemrand.denizencore.scripts.queues.ScriptEngine.revolve(ScriptEngine.java:54)
19:56:13 [SEVERE] net.aufdemrand.denizencore.scripts.queues.ScriptQueue.revolve(ScriptQueue.java:678)
19:56:13 [SEVERE] net.aufdemrand.denizencore.scripts.queues.core.TimedQueue.access$000(TimedQueue.java:12)
19:56:13 [SEVERE] net.aufdemrand.denizencore.scripts.queues.core.TimedQueue$1.run(TimedQueue.java:154)
19:56:13 [SEVERE] net.aufdemrand.denizencore.utilities.scheduling.RepeatingSchedulable.tick(RepeatingSchedulable.java:20)
19:56:13 [SEVERE] net.aufdemrand.denizencore.DenizenCore.tick(DenizenCore.java:155)
19:56:13 [SEVERE] net.aufdemrand.denizen.Denizen$2.run(Denizen.java:916)
19:56:13 [SEVERE] org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftTask.run(CraftTask.java:71)
19:56:13 [SEVERE] org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftScheduler.mainThreadHeartbeat(CraftScheduler.java:353)
19:56:13 [SEVERE] net.minecraft.server.v1_11_R1.MinecraftServer.D(MinecraftServer.java:738)
19:56:13 [SEVERE] net.minecraft.server.v1_11_R1.DedicatedServer.D(DedicatedServer.java:399)
19:56:13 [SEVERE] net.minecraft.server.v1_11_R1.MinecraftServer.C(MinecraftServer.java:678)
19:56:13 [SEVERE] net.minecraft.server.v1_11_R1.MinecraftServer.run(MinecraftServer.java:576)
19:56:13 [SEVERE] java.lang.Thread.run(Unknown Source)
19:56:13 [INFO] +> [Denizen] (Attempted: ADJUST "<player>" 
                   "show_entity:<def[masterguide]>") 
19:56:13 [INFO] +---------------------+ 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: ZAP "1" 
                   "script:s@guide_interact" 
19:56:13 [INFO] +- Executing dCommand: ZAP/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO] +> Executing 'ZAP': Player='p@Kaiser_Radbot'  
                   script='GUIDE_INTERACT(INTERACT)'  step='1' 
19:56:13 [INFO]  Queue 'GUIDE_PATH_HowtoReturningStorm' Executing: QUEUE 
                   "clear" 
19:56:13 [INFO] +- Executing dCommand: QUEUE/p@Kaiser_Radbot ---------+ 
19:56:13 [INFO] +> Executing 'QUEUE': queue='q@GUIDE_PATH_HowtoReturningStorm' 
                    Action='CLEAR' 
19:56:13 [INFO]  Completing queue 'GUIDE_PATH_HowtoReturningStorm' in 15456ms. 
19:56:57 [INFO]  Running script event 'PlayerLogin', event='player logs in' 
                   for script 'EVENTWORMLOGIN' 
19:56:57 [INFO]  Starting InstantQueue 'EVENTWORMLOGIN_TeamDumpWires'... 
19:56:57 [INFO]  Queue 'EVENTWORMLOGIN_TeamDumpWires' Executing: IF 
                   "<player.has_flag[Logged]>" "queue" "clear" 
19:56:57 [INFO] +- Executing dCommand: IF/p@Kaiser_Radbot ---------+ 
19:56:57 [INFO] +> Executing 'IF': use_braces='false' 
19:56:57 [INFO]  Filled tag <player.has_flag[Logged]> with 'true'. 
19:56:57 [INFO]  Queue 'EVENTWORMLOGIN_TeamDumpWires' Executing: QUEUE "clear" 
19:56:57 [INFO] +- Executing dCommand: QUEUE/p@Kaiser_Radbot ---------+ 
19:56:57 [INFO] +> Executing 'QUEUE': queue='q@EVENTWORMLOGIN_TeamDumpWires'  
                   Action='CLEAR' 
19:56:57 [INFO]  Completing queue 'EVENTWORMLOGIN_TeamDumpWires' in 0ms.