Paste #50090: Denizen Debug Logs From A Minecraft Server

Date: 2018/09/28 11:34:35 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link 


1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150



Java Version: 1.8.0_181
Up-time: 1m 32s
CraftBukkit Version: git-Spigot-2cf50f0-2b93d83 (MC: 1.12.2)
Denizen Version: Core: 1.15 (Build 156), CraftBukkit: 1.0.3-SNAPSHOT (build 417)
Active Plugins (7): NoCheatPlus: 3.15.0-SNAPSHOT-sMD5NET-b1063, Citizens: 2.0.24-SNAPSHOT (build 1568), 
Denizen: 1.0.3-SNAPSHOT (build 417), BlackBird: 1, Webizen: 0.2.0, Extendizen: 1, 
Depenizen: 1.0.0 (build 417)
Loaded Worlds (2): world, storyworld
Online Players (1): BlackCoyote(BlackCoyote)
Offline Players: 0
Mode: offline

14:34:24 [INFO]  Starting InstantQueue 'EXCOMMAND_CongressTheologyBoating'... 
14:34:24 [INFO]  Queue 'EXCOMMAND_CongressTheologyBoating' Executing: RUN "wizard_cutscene_1" 
14:34:24 [INFO] +- Executing dCommand: RUN/p@BlackCoyote ---------+ 
14:34:24 [INFO] +> Executing 'RUN': script='WIZARD_CUTSCENE_1(TASK)' 
14:34:24 [INFO]  Starting InstantQueue 'WIZARD_CUTSCENE_1_JazzWindowServe'... 
14:34:24 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: DEFINE "loc1" 
                   "l@<script.yaml_key[animation1loc]>,<proc[storymode_world].name>" 
14:34:24 [INFO] +- Executing dCommand: DEFINE/p@BlackCoyote ---------+ 
14:34:24 [INFO]  Filled tag <script.yaml_key[animation1loc]> with '1032.35,76.0625,606.58,1.7999005,-341.99908'. 
14:34:24 [INFO]  Filled tag <proc[storymode_world].name> with 'storyworld'. 
14:34:24 [INFO] +> Executing 'DEFINE': queue='WIZARD_CUTSCENE_1_JazzWindowServe'  definition='loc1'  
                   value='l@1032.35,76.0625,606.58,1.7999005,-341.99908,storyworld' 
14:34:24 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: CREATE "player" "Wizard" "<def[loc1]>" 
14:34:24 [INFO] +- Executing dCommand: CREATE/p@BlackCoyote ---------+ 
14:34:24 [INFO]  ...remembering this script entry as 'npc'! 
14:34:24 [INFO]  Filled tag <def[loc1]> with 'l@1032.35,76.0625,606.58,1.7999005,-341.99908,storyworld'. 
14:34:24 [INFO] +> Executing 'CREATE': name='Wizard'  entity_type='e@player'  
                   spawn_location='l@1032.35,76.0625,606.58,1.7999005,-341.99908,storyworld' 
14:34:24 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: DEFINE "npc" "<entry[npc].created_npc>" 
14:34:24 [INFO] +- Executing dCommand: DEFINE/p@BlackCoyote ---------+ 
14:34:24 [INFO]  Filled tag <entry[npc].created_npc> with 'n@2'. 
14:34:24 [INFO] +> Executing 'DEFINE': queue='WIZARD_CUTSCENE_1_JazzWindowServe'  definition='npc'  npc='n@2' 
14:34:24 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: WAIT "5s" 
14:34:24 [INFO] +- Executing dCommand: WAIT/p@BlackCoyote ---------+ 
14:34:24 [INFO] +> Executing 'WAIT': queue='WIZARD_CUTSCENE_1_JazzWindowServe'  delay='d@5.0s' 
14:34:24 [INFO]  Forcing queue WIZARD_CUTSCENE_1_JazzWindowServe into a timed queue... 
14:34:24 [INFO]  Completing queue 'WIZARD_CUTSCENE_1_JazzWindowServe' in 61ms. 
14:34:24 [INFO]  Starting TimedQueue 'WIZARD_CUTSCENE_1_JazzWindowServe'... 
14:34:24 [INFO]  Completing queue 'EXCOMMAND_CongressTheologyBoating' in 84ms. 
14:34:29 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: DEFINE "cameraloc" 
                   "l@<script.yaml_key[cameraloc]>,<proc[storymode_world].name>" 
14:34:29 [INFO] +- Executing dCommand: DEFINE/p@BlackCoyote ---------+ 
14:34:29 [INFO]  Filled tag <script.yaml_key[cameraloc]> with '1033.7,77.6,613.8'. 
14:34:29 [INFO]  Filled tag <proc[storymode_world].name> with 'storyworld'. 
14:34:29 [INFO] +> Executing 'DEFINE': queue='WIZARD_CUTSCENE_1_JazzWindowServe'  definition='cameraloc'  
                   value='l@1033.7,77.6,613.8,storyworld' 
14:34:29 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: DEFINE "camerafaceloc" 
                   "l@<script.yaml_key[camerafaceloc]>,<proc[storymode_world].name>" 
14:34:29 [INFO] +- Executing dCommand: DEFINE/p@BlackCoyote ---------+ 
14:34:29 [INFO]  Filled tag <script.yaml_key[camerafaceloc]> with '1028.5,76.5,614.5'. 
14:34:29 [INFO]  Filled tag <proc[storymode_world].name> with 'storyworld'. 
14:34:29 [INFO] +> Executing 'DEFINE': queue='WIZARD_CUTSCENE_1_JazzWindowServe'  definition='camerafaceloc'  
                   value='l@1028.5,76.5,614.5,storyworld' 
14:34:29 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: FOREACH "<proc[storymode_participants]>" 
14:34:29 [INFO] +- Executing dCommand: FOREACH/p@BlackCoyote ---------+ 
14:34:29 [INFO]  Filled tag <proc[storymode_participants]> with 'li@p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:29 [INFO] +> Executing 'FOREACH': list='li@p@3be6feff-a0dc-48ba-b797-3bf5517b7214' 
14:34:29 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: RUN "stationary_cutscene" 
                   "def:<def[cameraloc]>|<def[camerafaceloc]>|<def[value]>|20" 
14:34:29 [INFO] +- Executing dCommand: RUN/p@BlackCoyote ---------+ 
14:34:29 [INFO]  ...remembering this script entry as 'camera'! 
14:34:29 [INFO]  Filled tag <def[cameraloc]> with 'l@1033.7,77.6,613.8,storyworld'. 
14:34:29 [INFO]  Filled tag <def[camerafaceloc]> with 'l@1028.5,76.5,614.5,storyworld'. 
14:34:29 [INFO]  Filled tag <def[value]> with 'p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:29 [INFO] +> Executing 'RUN': script='STATIONARY_CUTSCENE(TASK)'  
                   definitions='l@1033.7,77.6,613.8,storyworld|l@1028.5,76.5,614.5,storyworld|p@3be6feff-a0dc-48ba-b797-3bf5517b7214|20' 
14:34:29 [INFO]  Adding definition %1% as l@1033.7,77.6,613.8,storyworld 
14:34:29 [INFO]  Adding definition %2% as l@1028.5,76.5,614.5,storyworld 
14:34:29 [INFO]  Adding definition %3% as p@3be6feff-a0dc-48ba-b797-3bf5517b7214 
14:34:29 [INFO]  Adding definition %4% as 20 
14:34:29 [INFO]  Starting InstantQueue 'STATIONARY_CUTSCENE_CountingInspectionsChrome'... 
14:34:29 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: CHUNKLOAD "add" "<def[1].get_chunk>" 
                   "duration:<def[4].add[5]||15>s" 
14:34:29 [INFO] +- Executing dCommand: CHUNKLOAD/p@BlackCoyote ---------+ 
14:34:29 [INFO]  Filled tag <def[1].get_chunk> with 'ch@64,38,storyworld'. 
14:34:29 [INFO]  Filled tag <def[4].add[5]||15> with '25'. 
14:34:29 [INFO] +> Executing 'CHUNKLOAD': action='ADD'  location='l@1032.0,128.0,616.0,storyworld'  duration='d@25.0s' 
14:34:29 [INFO]  ...added chunk 64, 38 with a delay of 25.0 seconds. 
14:34:29 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: SPAWN 
                   "e@armor_stand[marker=true;visible=false;gravity=false]" "<def[1].as_location>" 
14:34:29 [INFO] +- Executing dCommand: SPAWN/p@BlackCoyote ---------+ 
14:34:29 [INFO]  ...remembering this script entry as 'camera'! 
14:34:29 [INFO]  Filled tag <def[1].as_location> with 'l@1033.7,77.6,613.8,storyworld'. 
14:34:29 [INFO] +> Executing 'SPAWN': entities='[e@armor_stand[marker=true;visible=false;gravity=false]]'  
                   location='l@1033.7,77.6,613.8,storyworld' 
14:34:29 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: FLAG "<entry[camera].spawned_entities.get[1]>" "is_camera" 
14:34:29 [INFO] +- Executing dCommand: FLAG/p@BlackCoyote ---------+ 
14:34:29 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:29 [INFO] +> Executing 'FLAG': flag_name='is_camera'  Action/Value='SET_BOOLEAN(true)'  
                   flag_target='e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa' 
14:34:29 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: FLAG "<def[3]>" 
                   "camera:<entry[camera].spawned_entities.get[1]>" 
14:34:30 [INFO] +- Executing dCommand: FLAG/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled tag <def[3]> with 'p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:30 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:30 [INFO] +> Executing 'FLAG': flag_name='CAMERA'  Action/Value='SET_VALUE(e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa)'  
                   flag_target='p@BlackCoyote' 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: LOOK "<entry[camera].spawned_entities.get[1]>" 
                   "<def[2].as_location>" 
14:34:30 [INFO] +- Executing dCommand: LOOK/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:30 [INFO]  Filled tag <def[2].as_location> with 'l@1028.5,76.5,614.5,storyworld'. 
14:34:30 [INFO] +> Executing 'LOOK': location='l@1028.5,76.5,614.5,storyworld'  entities='[e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa]' 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: DEFINE "gamemode" "<def[3].gamemode>" 
14:34:30 [INFO] +- Executing dCommand: DEFINE/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled tag <def[3].gamemode> with 'ADVENTURE'. 
14:34:30 [INFO] +> Executing 'DEFINE': queue='STATIONARY_CUTSCENE_CountingInspectionsChrome'  definition='gamemode'  value='ADVENTURE' 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: ADJUST "%3%" 
                   "spectate:<entry[camera].spawned_entities.get[1]>" 
14:34:30 [INFO] +- Executing dCommand: ADJUST/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled definition %3% with 'p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:30 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:30 [INFO] +> Executing 'ADJUST': object='li@p@3be6feff-a0dc-48ba-b797-3bf5517b7214'  mechanism='spectate'  
                   mechanism_value='e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa' 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: ADJUST "%3%" "gamemode:spectator" 
14:34:30 [INFO] +- Executing dCommand: ADJUST/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled definition %3% with 'p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:30 [INFO] +> Executing 'ADJUST': object='li@p@3be6feff-a0dc-48ba-b797-3bf5517b7214'  mechanism='gamemode'  mechanism_value='spectator' 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: RUN "stop_stationary_cutscene" 
                   "def:<def[gamemode]>|<entry[camera].spawned_entities.get[1]>" "delay:<def[4]||10>s" "instantly" 
14:34:30 [INFO] +- Executing dCommand: RUN/p@BlackCoyote ---------+ 
14:34:30 [INFO]  ...replacing the linked player with <def[3]> 
14:34:30 [INFO]  Filled tag <def[3]> with 'p@3be6feff-a0dc-48ba-b797-3bf5517b7214'. 
14:34:30 [INFO]  Filled tag <def[gamemode]> with 'ADVENTURE'. 
14:34:30 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:30 [INFO]  Filled tag <def[4]||10> with '20'. 
14:34:30 [INFO] +> Executing 'RUN': script='STOP_STATIONARY_CUTSCENE(TASK)'  instant='true'  delay='d@20.0s'  
                   definitions='ADVENTURE|e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa' 
14:34:30 [INFO]  Adding definition %1% as ADVENTURE 
14:34:30 [INFO]  Adding definition %2% as e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa 
14:34:30 [INFO]  Delaying InstantQueue 'STOP_STATIONARY_CUTSCENE_InfAxisStylish' for 'd@19.999s'... 
14:34:30 [INFO]  Queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' Executing: DETERMINE "<entry[camera].spawned_entities.get[1]>" 
14:34:30 [INFO] +- Executing dCommand: DETERMINE/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled tag <entry[camera].spawned_entities.get[1]> with 'e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'. 
14:34:30 [INFO] +> Executing 'DETERMINE': outcome='e@2a3c61b1-87ca-4e7a-b0b7-ba031aa0bbfa'  passively='false' 
14:34:30 [INFO]  Completing queue 'STATIONARY_CUTSCENE_CountingInspectionsChrome' in 365ms. 
14:34:30 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: FOREACH " CALLBACK" 
14:34:30 [INFO] +- Foreach loop complete ---------+ 
14:34:30 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: motioncapture "id:c1w1" "load" 
                   "path:animations/episode1/cutscene1wizard1.yml" 
14:34:30 [INFO] +- Executing dCommand: motioncapture/p@BlackCoyote ---------+ 
14:34:30 [INFO] +> Executing 'MOTIONCAPTURE': id='c1w1'  action='load'  path='animations/episode1/cutscene1wizard1.yml' 
14:34:30 [INFO]  ERROR in script 'WIZARD_CUTSCENE_1'! Animation c1w1 already exists! 
14:34:30 [INFO]  Queue 'WIZARD_CUTSCENE_1_JazzWindowServe' Executing: motioncapture "id:c1w1" "play" "<def[npc]>" 
14:34:30 [INFO] +- Executing dCommand: motioncapture/p@BlackCoyote ---------+ 
14:34:30 [INFO]  Filled tag <def[npc]> with 'n@2'. 
14:34:30 [INFO] +> Executing 'MOTIONCAPTURE': id='c1w1'  action='play'  entity='n@2'