Paste #48536: Denizen Debug Logs From adventure time!!!

Date: 2018/07/09 22:51:28 UTC-07:00
Type: Server Log

View Raw Paste Download This Paste
Copy Link


Java Version: 1.8.0_144
Up-time: 44m
CraftBukkit Version: git-Spigot-642f6d2-6103339 (MC: 1.12.2)
Denizen Version: Core: 1.15 (Build 144), CraftBukkit: 1.0.2-SNAPSHOT (build 379)
Active Plugins (4): iConomy: 7.0, Vault: 1.5.6-b49, Citizens: 2.0.22-SNAPSHOT (build 1552), Denizen: 1.0.2-SNAPSHOT (build 379),
Loaded Worlds (3): adventure map, adventure map_nether, adventure map_the_end
Online Players (1): calicokid(calicokid)
Offline Players: 76
Mode: online

22:51:13 [INFO] +> Executing 'Event': Type='on STORE_SETUP command'  Container='ITEM FRAME SHOP PLAYER OWNED(WORLD)'  
                   player=p@calicokidContext='{args=li@, cuboids=li@, server=false, parsed_args=li@, command=STORE_SETUP, raw_args=}' 
22:51:13 [INFO] +- Building event 'ON STORE_SETUP COMMAND' for ITEM FRAME SHOP PLAYER OWNED ---------+ 
22:51:13 [INFO]  Starting InstantQueue 'ITEM FRAME SHOP PLAYER OWNED_StakeholdersAutomotiveExpedia'... 
22:51:13 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_StakeholdersAutomotiveExpedia' Executing: GIVE "i@storekeeperegg" 
22:51:13 [INFO] +- Executing dCommand: GIVE/p@calicokid ---------+ 
22:51:13 [INFO] +> Executing 'GIVE': Type='ITEM'  inventory='in@player[holder=p@ba85964b-f511-4ca7-b1ca-df6ce1f78755]'  Quantity='1.0'  
                   engrave='false'  unlimit_stack_size='false'  Items='[i@STOREKEEPEREGG[display_name=shop_keeper]]'  slot='1' 
22:51:13 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_StakeholdersAutomotiveExpedia' Executing: DETERMINE "fulfilled" 
22:51:13 [INFO] +- Executing dCommand: DETERMINE/p@calicokid ---------+ 
22:51:13 [INFO] +> Executing 'DETERMINE': outcome='fulfilled'  passively='false' 
22:51:13 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_StakeholdersAutomotiveExpedia' in 2ms. 
22:51:16 [INFO]  Filled tag <red> with ''. 
22:51:16 [INFO]  Running script event 'PlayerClicksBlock', event='player clicks with storekeeperegg' for script 'ITEM FRAME SHOP PLAYER OWNED' 
22:51:16 [INFO]  Starting InstantQueue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa'... 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: IF "<global.flag[store].escape_contents||true>" "{" "-" 
                   "create" "player" "storekeeper" "<player.location.cursor_on.add[0.5,1,0.5]>" "save:mystorekeeper" "-" "wait" "2t" "-" "flag" 
                   "player" "storenpc:<entry[mystorekeeper].created_npc>" "-" "narrate" "<dark_purple>please select the bottom and top corners of 
                   your cuboid then drop the selector" "-" "take" "i@storekeeperegg" "-" "wait" "1t" "-" "lookclose" 
                   "<entry[mystorekeeper].created_npc>" "state:true" "-" "assignment" "set" "npc:<entry[mystorekeeper].created_npc.id>" 
                   "script:StorekeeperPlayerOwned" "-" "give" "i@storelocationstick" "-" "queue" "clear" "}" 
22:51:16 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'IF': use_braces='true' 
22:51:16 [INFO]  Filled tag <global.flag[store].escape_contents||true> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: FOREACH "<global.flag[store].escape_contents||li@>" "{" 
                   "-" "if" "<context.location.is_within[<def[value].unescaped.as_cuboid||false>]>" "{" "-" "narrate" "you cannot place your 
                   store inside of another store" "-" "determine" "cancelled" "}" "}" 
22:51:16 [INFO] +- Executing dCommand: FOREACH/p@calicokid ---------+ 
22:51:16 [INFO]  Filled tag <global.flag[store].escape_contents||li@> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:16 [INFO] +> Executing 'FOREACH': list='li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: IF 
                   "<context.location.is_within[<def[value].unescaped.as_cuboid||false>]>" "{" "-" "narrate" "you cannot place your store inside 
                   of another store" "-" "determine" "cancelled" "}" 
22:51:16 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'IF': use_braces='true' 
22:51:16 [INFO]  Filled tag <def[value].unescaped.as_cuboid||false> with 'cu@-1588,62,371,adventure map|-1577,71,382,adventure map'. 
22:51:16 [INFO]  Filled tag <context.location.is_within[cu@-1588,62,371,adventure map|-1577,71,382,adventure map]> with 'false'. 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: FOREACH " CALLBACK" 
22:51:16 [INFO] +- Foreach loop complete ---------+ 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: FOREACH "<global.flag[store].escape_contents||li@>" "{" 
                   "-" "if" "!<context.location.is_within[<def[value].unescaped.as_cuboid||false>]>" "{" "-" "create" "player" "storekeeper" 
                   "<player.location.cursor_on.add[0.5,1,0.5]>" "save:mystorekeeper" "-" "execute" "as_op" "npc select" "-" "narrate" 
                   "<dark_purple>please select the bottom and top corners of your cuboid then drop the selector" "-" "take" "i@storekeeperegg" 
                   "-" "flag" "player" "storenpc:<entry[mystorekeeper].created_npc>" "-" "lookclose" "<entry[mystorekeeper].created_npc>" 
                   "state:true" "-" "wait" "1t" "-" "assignment" "set" "npc:<entry[mystorekeeper].created_npc>" "script:StorekeeperPlayerOwned" 
                   "-" "give" "i@storelocationstick" "-" "queue" "clear" "}" "}" 
22:51:16 [INFO] +- Executing dCommand: FOREACH/p@calicokid ---------+ 
22:51:16 [INFO]  Filled tag <global.flag[store].escape_contents||li@> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:16 [INFO] +> Executing 'FOREACH': list='li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: IF 
                   "!<context.location.is_within[<def[value].unescaped.as_cuboid||false>]>" "{" "-" "create" "player" "storekeeper" 
                   "<player.location.cursor_on.add[0.5,1,0.5]>" "save:mystorekeeper" "-" "execute" "as_op" "npc select" "-" "narrate" 
                   "<dark_purple>please select the bottom and top corners of your cuboid then drop the selector" "-" "take" "i@storekeeperegg" 
                   "-" "flag" "player" "storenpc:<entry[mystorekeeper].created_npc>" "-" "lookclose" "<entry[mystorekeeper].created_npc>" 
                   "state:true" "-" "wait" "1t" "-" "assignment" "set" "npc:<entry[mystorekeeper].created_npc>" "script:StorekeeperPlayerOwned" 
                   "-" "give" "i@storelocationstick" "-" "queue" "clear" "}" 
22:51:16 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'IF': use_braces='true' 
22:51:16 [INFO]  Filled tag <def[value].unescaped.as_cuboid||false> with 'cu@-1588,62,371,adventure map|-1577,71,382,adventure map'. 
22:51:16 [INFO]  Filled tag <context.location.is_within[cu@-1588,62,371,adventure map|-1577,71,382,adventure map]> with 'false'. 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: CREATE "player" "storekeeper" 
                   "<player.location.cursor_on.add[0.5,1,0.5]>" 
22:51:16 [INFO] +- Executing dCommand: CREATE/p@calicokid ---------+ 
22:51:16 [INFO]  ...remembering this script entry as 'mystorekeeper'! 
22:51:16 [INFO]  Filled tag <player.location.cursor_on.add[0.5,1,0.5]> with 'l@246.5,64.0,298.5,adventure map'. 
22:51:16 [INFO] +> Executing 'CREATE': name='storekeeper'  entity_type='e@player'  spawn_location='l@246.5,64.0,298.5,adventure map' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: EXECUTE "as_op" "npc select" 
22:51:16 [INFO] +- Executing dCommand: EXECUTE/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'EXECUTE': type='AS_OP'  command='npc select'  silent='false' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: NARRATE "<dark_purple>please select the bottom and top 
                   corners of your cuboid then drop the selector" 
22:51:16 [INFO] +- Executing dCommand: NARRATE/p@calicokid ---------+ 
22:51:16 [INFO]  Filled tag <dark_purple> with ''. 
22:51:16 [INFO] +> Executing 'NARRATE': Narrating='please select the bottom and top corners of your cuboid then drop the selector'  
                   Targets='[p@calicokid]' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: TAKE "i@storekeeperegg" 
22:51:16 [INFO] +- Executing dCommand: TAKE/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'TAKE': Type='ITEM'  qty='1'  inventory='in@player[holder=p@ba85964b-f511-4ca7-b1ca-df6ce1f78755]'  
                   Items='[i@STOREKEEPEREGG[display_name=shop_keeper]]' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: FLAG "player" 
                   "storenpc:<entry[mystorekeeper].created_npc>" 
22:51:16 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:16 [INFO]  Filled tag <entry[mystorekeeper].created_npc> with 'n@45'. 
22:51:16 [INFO] +> Executing 'FLAG': flag_name='STORENPC'  Action/Value='SET_VALUE(n@45)'  flag_target='p@calicokid' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: LOOKCLOSE "<entry[mystorekeeper].created_npc>" 
                   "state:true" 
22:51:16 [INFO] +- Executing dCommand: LOOKCLOSE/p@calicokid ---------+ 
22:51:16 [INFO]  Filled tag <entry[mystorekeeper].created_npc> with 'n@45'. 
22:51:16 [INFO] +> Executing 'LOOKCLOSE': npc='n@45'  realistic='null'  range='null'  toggle='true' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: WAIT "1t" 
22:51:16 [INFO] +- Executing dCommand: WAIT/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'WAIT': queue='ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa'  delay='d@0.05s' 
22:51:16 [INFO]  Forcing queue ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa into a timed queue... 
22:51:16 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' in 28ms. 
22:51:16 [INFO]  Starting TimedQueue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa'... 
22:51:16 [INFO] +> [dItem] Does not match a valid item ID or material: shopping 
22:51:16 [INFO] +> [dItem] valueOf dItem returning null: shopping 
22:51:16 [INFO]  ERROR! Invalid WITH item in PlayerClicksBlock for 'player right clicks with shopping bag' in ITEM FRAME SHOP PLAYER OWNED 
22:51:16 [INFO]  Filled tag <green> with ''. 
22:51:16 [INFO]  Filled tag <green> with ''. 
22:51:16 [INFO]  Filled tag <green> with ''. 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: ASSIGNMENT "set" "script:StorekeeperPlayerOwned" 
22:51:16 [INFO] +- Executing dCommand: ASSIGNMENT/p@calicokid ---------+ 
22:51:16 [INFO]  ...replacing the linked NPC with <entry[mystorekeeper].created_npc> 
22:51:16 [INFO]  Filled tag <entry[mystorekeeper].created_npc> with 'n@45'. 
22:51:16 [INFO] +> Executing 'ASSIGNMENT': action='SET'  script='STOREKEEPERPLAYEROWNED(ASSIGNMENT)' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: GIVE "i@storelocationstick" 
22:51:16 [INFO] +- Executing dCommand: GIVE/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'GIVE': Type='ITEM'  inventory='in@player[holder=p@ba85964b-f511-4ca7-b1ca-df6ce1f78755]'  Quantity='1.0'  
                   engrave='false'  unlimit_stack_size='false'  Items='[i@STORELOCATIONSTICK[display_name=store_selector_tool]]'  slot='1' 
22:51:16 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' Executing: QUEUE "clear" 
22:51:16 [INFO] +- Executing dCommand: QUEUE/p@calicokid ---------+ 
22:51:16 [INFO] +> Executing 'QUEUE': queue='q@ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa'  Action='CLEAR' 
22:51:16 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_SchemaPresentationJa' in 74ms. 
22:51:16 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:16 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:17 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:18 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:19 [INFO]  Filled tag <red> with ''. 
22:51:19 [INFO] +> [dItem] Does not match a valid item ID or material: shopping 
22:51:19 [INFO] +> [dItem] valueOf dItem returning null: shopping 
22:51:19 [INFO]  ERROR! Invalid WITH item in PlayerClicksBlock for 'player right clicks with shopping bag' in ITEM FRAME SHOP PLAYER OWNED 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  Running script event 'PlayerClicksBlock', event='player right clicks with storelocationstick' for script 'ITEM FRAME SHOP 
                   PLAYER OWNED' 
22:51:19 [INFO]  Starting InstantQueue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve'... 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: IF "<global.flag[store].escape_contents||null>" "==" "null" "{" 
                   "-" "flag" "player" "location1:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 1 selected" "-" "determine" 
                   "passively" "cancelled" "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "else" "{" "-" 
                   "foreach" "<global.flag[store].escape_contents||li@>" "{" "-" "if" 
                   "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your store in 
                   another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" "player" 
                   "location1:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 1 selected" "-" "determine" "passively" "cancelled" 
                   "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "}" "}" 
22:51:19 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:19 [INFO] +> Executing 'IF': use_braces='true' 
22:51:19 [INFO]  Filled tag <global.flag[store].escape_contents||null> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:19 [INFO]  Comparable='dList(li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map)', 
                   Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: FOREACH "<global.flag[store].escape_contents||li@>" "{" "-" 
                   "if" "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your 
                   store in another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" 
                   "player" "location1:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 1 selected" "-" "determine" "passively" 
                   "cancelled" "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "}" 
22:51:19 [INFO] +- Executing dCommand: FOREACH/p@calicokid ---------+ 
22:51:19 [INFO]  Filled tag <global.flag[store].escape_contents||li@> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:19 [INFO] +> Executing 'FOREACH': list='li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: IF 
                   "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your store in 
                   another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" "player" 
                   "location1:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 1 selected" "-" "determine" "passively" "cancelled" 
                   "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" 
22:51:19 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:19 [INFO] +> Executing 'IF': use_braces='true' 
22:51:19 [INFO]  Filled tag <def[value].unescaped.as_cuboid||false> with 'cu@-1588,62,371,adventure map|-1577,71,382,adventure map'. 
22:51:19 [INFO]  Filled tag <context.location.is_within[cu@-1588,62,371,adventure map|-1577,71,382,adventure map]||false> with 'false'. 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: FLAG "player" "location1:<context.location>" 
22:51:19 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:19 [INFO]  Filled tag <context.location> with 'l@243.0,68.0,300.0,adventure map'. 
22:51:19 [INFO] +> Executing 'FLAG': flag_name='LOCATION1'  Action/Value='SET_VALUE(l@243.0,68.0,300.0,adventure map)'  
                   flag_target='p@calicokid' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: NARRATE "<dark_purple>Cuboid location 1 selected" 
22:51:19 [INFO] +- Executing dCommand: NARRATE/p@calicokid ---------+ 
22:51:19 [INFO]  Filled tag <dark_purple> with ''. 
22:51:19 [INFO] +> Executing 'NARRATE': Narrating='Cuboid location 1 selected'  Targets='[p@calicokid]' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: DETERMINE "passively" "cancelled" 
22:51:19 [INFO] +- Executing dCommand: DETERMINE/p@calicokid ---------+ 
22:51:19 [INFO] +> Executing 'DETERMINE': outcome='cancelled'  passively='true' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: WAIT "1t" 
22:51:19 [INFO] +- Executing dCommand: WAIT/p@calicokid ---------+ 
22:51:19 [INFO] +> Executing 'WAIT': queue='ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve'  delay='d@0.05s' 
22:51:19 [INFO]  Forcing queue ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve into a timed queue... 
22:51:19 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' in 12ms. 
22:51:19 [INFO]  Starting TimedQueue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve'... 
22:51:19 [INFO]  Event cancelled! 
22:51:19 [INFO]  Filled tag <red> with ''. 
22:51:19 [INFO] +> [dItem] Does not match a valid item ID or material: shopping 
22:51:19 [INFO] +> [dItem] valueOf dItem returning null: shopping 
22:51:19 [INFO]  ERROR! Invalid WITH item in PlayerClicksBlock for 'player right clicks with shopping bag' in ITEM FRAME SHOP PLAYER OWNED 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  Filled tag <green> with ''. 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: SHOWFAKE "m@red_wool" "<c.location>" "to:<player>" "d:10s" 
22:51:19 [INFO] +- Executing dCommand: SHOWFAKE/p@calicokid ---------+ 
22:51:19 [INFO]  Filled tag <c.location> with 'l@243.0,68.0,300.0,adventure map'. 
22:51:19 [INFO]  Filled tag <player> with 'p@ba85964b-f511-4ca7-b1ca-df6ce1f78755'. 
22:51:19 [INFO] +> Executing 'SHOWFAKE': materials='li@m@red_wool'  locations='li@l@243.0,68.0,300.0,adventure map'  
                   entities='li@p@ba85964b-f511-4ca7-b1ca-df6ce1f78755'  duration='d@10.0s'  cancel='false' 
22:51:19 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' Executing: FOREACH " CALLBACK" 
22:51:19 [INFO] +- Foreach loop complete ---------+ 
22:51:19 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_RaNicoleSolve' in 87ms. 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:19 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:20 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:21 [INFO]  Running script event 'PlayerClicksBlock', event='player left clicks with storelocationstick' for script 'ITEM FRAME SHOP 
                   PLAYER OWNED' 
22:51:21 [INFO]  Starting InstantQueue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe'... 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: IF "<global.flag[store].escape_contents||null>" "==" "null" 
                   "{" "-" "flag" "player" "location2:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 2 selected" "-" "determine" 
                   "passively" "cancelled" "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "else" "{" "-" 
                   "foreach" "<global.flag[store].escape_contents||li@>" "{" "-" "if" 
                   "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your store in 
                   another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" "player" 
                   "location2:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 2 selected" "-" "determine" "passively" "cancelled" 
                   "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "}" "}" 
22:51:21 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:21 [INFO] +> Executing 'IF': use_braces='true' 
22:51:21 [INFO]  Filled tag <global.flag[store].escape_contents||null> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:21 [INFO]  Comparable='dList(li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map)', 
                   Operator='EQUALS', ComparedTo='Element(null)' --> OUTCOME='false' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: FOREACH "<global.flag[store].escape_contents||li@>" "{" "-" 
                   "if" "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your 
                   store in another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" 
                   "player" "location2:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 2 selected" "-" "determine" "passively" 
                   "cancelled" "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" "}" 
22:51:21 [INFO] +- Executing dCommand: FOREACH/p@calicokid ---------+ 
22:51:21 [INFO]  Filled tag <global.flag[store].escape_contents||li@> with 'li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map'. 
22:51:21 [INFO] +> Executing 'FOREACH': list='li@cu&atl&at-1588&dot0,71&dot0,382&dot0,adventure 
                   map&pipel&at-1577&dot0,62&dot0,371&dot0,adventure map' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: IF 
                   "<context.location.is_within[<def[value].unescaped.as_cuboid>]||false>" "{" "-" "narrate" "<red>You cannot have your store in 
                   another store location please select a different location" "-" "determine" "cancelled" "}" "else" "{" "-" "flag" "player" 
                   "location2:<context.location>" "-" "narrate" "<dark_purple>Cuboid location 2 selected" "-" "determine" "passively" "cancelled" 
                   "-" "wait" "1t" "-" "showfake" "m@red_wool" "<c.location>" "to:<player>" "d:10s" "}" 
22:51:21 [INFO] +- Executing dCommand: IF/p@calicokid ---------+ 
22:51:21 [INFO] +> Executing 'IF': use_braces='true' 
22:51:21 [INFO]  Filled tag <def[value].unescaped.as_cuboid||false> with 'cu@-1588,62,371,adventure map|-1577,71,382,adventure map'. 
22:51:21 [INFO]  Filled tag <context.location.is_within[cu@-1588,62,371,adventure map|-1577,71,382,adventure map]||false> with 'false'. 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: FLAG "player" "location2:<context.location>" 
22:51:21 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:21 [INFO]  Filled tag <context.location> with 'l@247.0,63.0,295.0,adventure map'. 
22:51:21 [INFO] +> Executing 'FLAG': flag_name='LOCATION2'  Action/Value='SET_VALUE(l@247.0,63.0,295.0,adventure map)'  
                   flag_target='p@calicokid' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: NARRATE "<dark_purple>Cuboid location 2 selected" 
22:51:21 [INFO] +- Executing dCommand: NARRATE/p@calicokid ---------+ 
22:51:21 [INFO]  Filled tag <dark_purple> with ''. 
22:51:21 [INFO] +> Executing 'NARRATE': Narrating='Cuboid location 2 selected'  Targets='[p@calicokid]' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: DETERMINE "passively" "cancelled" 
22:51:21 [INFO] +- Executing dCommand: DETERMINE/p@calicokid ---------+ 
22:51:21 [INFO] +> Executing 'DETERMINE': outcome='cancelled'  passively='true' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: WAIT "1t" 
22:51:21 [INFO] +- Executing dCommand: WAIT/p@calicokid ---------+ 
22:51:21 [INFO] +> Executing 'WAIT': queue='ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe'  delay='d@0.05s' 
22:51:21 [INFO]  Forcing queue ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe into a timed queue... 
22:51:21 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' in 13ms. 
22:51:21 [INFO]  Starting TimedQueue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe'... 
22:51:21 [INFO]  Event cancelled! 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: SHOWFAKE "m@red_wool" "<c.location>" "to:<player>" "d:10s" 
22:51:21 [INFO] +- Executing dCommand: SHOWFAKE/p@calicokid ---------+ 
22:51:21 [INFO]  Filled tag <c.location> with 'l@247.0,63.0,295.0,adventure map'. 
22:51:21 [INFO]  Filled tag <player> with 'p@ba85964b-f511-4ca7-b1ca-df6ce1f78755'. 
22:51:21 [INFO] +> Executing 'SHOWFAKE': materials='li@m@red_wool'  locations='li@l@247.0,63.0,295.0,adventure map'  
                   entities='li@p@ba85964b-f511-4ca7-b1ca-df6ce1f78755'  duration='d@10.0s'  cancel='false' 
22:51:21 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' Executing: FOREACH " CALLBACK" 
22:51:21 [INFO] +- Foreach loop complete ---------+ 
22:51:21 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_SilverTaggedAe' in 88ms. 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:21 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! Tag <npc.flag[store].as_cuboid> is invalid! 
22:51:22 [INFO]  ERROR in script 'STOREKEEPERPLAYEROWNED'! valueOf dCuboid returning null: npc.flag[store].as_cuboid 
22:51:23 [INFO]  Running script event 'PlayerDropsItem', event='player drops storelocationstick' for script 'ITEM FRAME SHOP PLAYER OWNED' 
22:51:23 [INFO]  Starting InstantQueue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker'... 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: EXECUTE "as_op" "npc owner <player.name> --id 
                   <player.flag[storenpc].id>" 
22:51:23 [INFO] +- Executing dCommand: EXECUTE/p@calicokid ---------+ 
22:51:23 [INFO]  Filled tag <player.name> with 'calicokid'. 
22:51:23 [INFO]  Unfilled attributes 'id' for tag <player.flag[storenpc].id>! 
22:51:23 [INFO]  Filled tag <player.flag[storenpc].id> with 'null'. 
22:51:23 [INFO]  ERROR in script 'ITEM FRAME SHOP PLAYER OWNED'! Tag <player.flag[storenpc].id> is invalid! 
22:51:23 [INFO] +> Executing 'EXECUTE': type='AS_OP'  command='npc owner calicokid --id player.flag[storenpc].id'  silent='false' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: FLAG "<player.flag[storenpc]>" 
                   "store:cu@<player.flag[location1]>|<player.flag[location2]>" 
22:51:23 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:23 [INFO]  Filled tag <player.flag[storenpc]> with 'n@45'. 
22:51:23 [INFO]  Filled tag <player.flag[location1]> with 'l@243.0,68.0,300.0,adventure map'. 
22:51:23 [INFO]  Filled tag <player.flag[location2]> with 'l@247.0,63.0,295.0,adventure map'. 
22:51:23 [INFO] +> Executing 'FLAG': flag_name='STORE'  Action/Value='SET_VALUE(cu@l@243.0,68.0,300.0,adventure 
                   map|l@247.0,63.0,295.0,adventure map)'  npc='n@45' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: FLAG "player" "location1:!" 
22:51:23 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:23 [INFO] +> Executing 'FLAG': flag_name='LOCATION1'  Action/Value='DELETE(false)'  flag_target='p@calicokid' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: FLAG "player" "location2:!" 
22:51:23 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:23 [INFO] +> Executing 'FLAG': flag_name='LOCATION2'  Action/Value='DELETE(false)'  flag_target='p@calicokid' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: FLAG "server" "storenpcs:->:<player.flag[storenpc]>" 
22:51:23 [INFO] +- Executing dCommand: FLAG/p@calicokid ---------+ 
22:51:23 [INFO]  Filled tag <player.flag[storenpc]> with 'n@45'. 
22:51:23 [INFO] +> Executing 'FLAG': flag_name='STORENPCS'  Action/Value='INSERT(n@45)'  flag_target='server' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: NARRATE "<dark_purple>tell the npc <green>'SET' 
                   <dark_purple>to set the items in the frame" 
22:51:23 [INFO] +- Executing dCommand: NARRATE/p@calicokid ---------+ 
22:51:23 [INFO]  Filled tag <dark_purple> with ''. 
22:51:23 [INFO]  Filled tag <green> with ''. 
22:51:23 [INFO]  Filled tag <dark_purple> with ''. 
22:51:23 [INFO] +> Executing 'NARRATE': Narrating='tell the npc 'SET' to set the items in the frame'  Targets='[p@calicokid]' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: DETERMINE "passively" "cancelled" 
22:51:23 [INFO] +- Executing dCommand: DETERMINE/p@calicokid ---------+ 
22:51:23 [INFO] +> Executing 'DETERMINE': outcome='cancelled'  passively='true' 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: WAIT "1t" 
22:51:23 [INFO] +- Executing dCommand: WAIT/p@calicokid ---------+ 
22:51:23 [INFO] +> Executing 'WAIT': queue='ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker'  delay='d@0.05s' 
22:51:23 [INFO]  Forcing queue ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker into a timed queue... 
22:51:23 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' in 16ms. 
22:51:23 [INFO]  Starting TimedQueue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker'... 
22:51:23 [INFO]  Event cancelled! 
22:51:23 [INFO]  Queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' Executing: TAKE "storelocationstick" 
22:51:23 [INFO] +- Executing dCommand: TAKE/p@calicokid ---------+ 
22:51:23 [INFO] +> Executing 'TAKE': Type='ITEM'  qty='1'  inventory='in@player[holder=p@ba85964b-f511-4ca7-b1ca-df6ce1f78755]'  
                   Items='[i@STORELOCATIONSTICK[display_name=store_selector_tool]]' 
22:51:23 [INFO]  Completing queue 'ITEM FRAME SHOP PLAYER OWNED_LikelyMetParker' in 87ms.