Java Version: 1.8.0_91
Up-time: 14m
CraftBukkit Version: git-Spigot-6016ac7-d5ecbd0 (MC: 1.10)
Denizen Version: Core: 1.10 (Build 141), CraftBukkit: 0.9.8-SNAPSHOT (build 598)
Active Plugins (3): Citizens: 2.0.19-SNAPSHOT (build 1375), Denizen: 0.9.8-SNAPSHOT (build 598),
Depenizen: 0.2.2 (build 316)
Loaded Worlds (3): world, world_nether, world_the_end
Online Players (1): WizardsMine(WizardsMine)
Offline Players: 1
Mode: offline
16:36:23 [INFO] Running script event 'PostLogin', event='bungee player joins
network' for script 'GLOBAL_LOGIN'
16:36:23 [INFO] Context 'name' = 'WizardsMine'
16:36:23 [INFO] Context 'uuid' = '7af2ce60-44c7-42db-b793-fbfd2879e599'
16:36:23 [INFO] Starting InstantQueue 'GLOBAL_LOGIN_RnDpiFootball'...
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_RnDpiFootball' Executing: WAIT "4"
16:36:23 [INFO] +- Executing dCommand: WAIT ---------+
16:36:23 [INFO] +> Executing 'WAIT': queue='GLOBAL_LOGIN_RnDpiFootball'
delay='d@4.0s'
16:36:23 [INFO] Forcing queue GLOBAL_LOGIN_RnDpiFootball into a timed
queue...
16:36:23 [INFO] Completing queue 'GLOBAL_LOGIN_RnDpiFootball' in 7ms.
16:36:23 [INFO] Starting TimedQueue 'GLOBAL_LOGIN_RnDpiFootball'...
16:36:23 [INFO] Running script event 'PlayerLogin', event='player logs in'
for script 'GLOBAL_LOGIN'
16:36:23 [INFO] Starting InstantQueue 'GLOBAL_LOGIN_ComplyRadiosFusion'...
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: FLAG
"player" "login_procedure"
16:36:23 [INFO] +- Executing dCommand: FLAG/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'FLAG': flag_name='login_procedure'
Action/Value='SET_BOOLEAN(true)' flag_target='p@WizardsMine'
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: RUN
"set_ban_state" "def:<pl>" "instantly"
16:36:23 [INFO] +- Executing dCommand: RUN/p@WizardsMine ---------+
16:36:23 [INFO] Filled tag <pl> with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] +> Executing 'RUN': script='SET_BAN_STATE(TASK)'
instant='true'
definitions='li@p@7af2ce60-44c7-42db-b793-fbfd2879e599'
16:36:23 [INFO] Adding definition %player% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:23 [INFO] Starting InstantQueue 'SET_BAN_STATE_PokemonPestConcerns'...
16:36:23 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: IF
"<proc[check_yaml_ban_state].context[%player%]>" "==" "false"
"{" "-" "run" "change_expired_bans" "def:%player%" "instantly"
"-" "if"
"<proc[check_sql_ban_state].context[<def[player].uuid>]>" "=="
"true" "{" "-" "~sql" "id:mcserver" "query:SELECT * FROM
playerbans WHERE uuid='%uuid%' AND unbanned='0' ORDER BY
ban_expire;" "save:bans" "-" "foreach"
"<entry[bans].result||li@>" "{" "-" "if"
"<def[value].split[/].get[6]>" "==" "1" "{" "-" "define"
"ban_ex... * snip! *
16:36:23 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:23 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] +> Executing 'IF': use_braces='true'
16:36:23 [INFO] Adding definition %player% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:23 [INFO] Starting InstantQueue
'CHECK_YAML_BAN_STATE_VoluntaryForecastsHopefully'...
16:36:23 [INFO] Queue 'CHECK_YAML_BAN_STATE_VoluntaryForecastsHopefully'
Executing: IF "!<yaml[bans].contains[players.%player%]>" "{"
"-" "determine" "false" "}"
16:36:23 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:23 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] +> Executing 'IF': use_braces='true'
16:36:23 [INFO] Filled tag
<yaml[bans].contains[players.p@7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'false'.
16:36:23 [INFO] Queue 'CHECK_YAML_BAN_STATE_VoluntaryForecastsHopefully'
Executing: DETERMINE "false"
16:36:23 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'DETERMINE': outcome='false' passively='false'
16:36:23 [INFO] Completing queue
'CHECK_YAML_BAN_STATE_VoluntaryForecastsHopefully' in 26ms.
16:36:23 [INFO] Filled tag
<proc[check_yaml_ban_state].context[p@7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'false'.
16:36:23 [INFO] Comparable='Element(false)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='true'
16:36:23 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: RUN
"change_expired_bans" "def:%player%" "instantly"
16:36:23 [INFO] +- Executing dCommand: RUN/p@WizardsMine ---------+
16:36:23 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] +> Executing 'RUN': script='CHANGE_EXPIRED_BANS(TASK)'
instant='true'
definitions='li@p@7af2ce60-44c7-42db-b793-fbfd2879e599'
16:36:23 [INFO] Adding definition %uuid% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:23 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: IF
"<proc[check_sql_ban_state].context[<def[player].uuid>]>" "=="
"true" "{" "-" "~sql" "id:mcserver" "query:SELECT * FROM
playerbans WHERE uuid='%uuid%' AND unbanned='0' ORDER BY
ban_expire;" "save:bans" "-" "foreach"
"<entry[bans].result||li@>" "{" "-" "if"
"<def[value].split[/].get[6]>" "==" "1" "{" "-" "define"
"ban_expire" "<def[value].split[/].get[6]>" "-" "define"
"reason" "<def[value].split[/].get[7]>" "-" "foreach" "stop"
"}" "-" "if" "<def[ban_... * snip! *
16:36:23 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'IF': use_braces='true'
16:36:23 [INFO] Filled tag <def[player].uuid> with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] Adding definition %uuid% as
7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:23 [INFO] Starting InstantQueue
'CHECK_SQL_BAN_STATE_WorryGrassRisks'...
16:36:23 [INFO] Queue 'CHECK_SQL_BAN_STATE_WorryGrassRisks' Executing: SQL
"id:mcserver" "query:SELECT count(*) FROM playerbans WHERE
uuid='%uuid%' AND unbanned='0';" "save:has_ban"
16:36:23 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:23 [INFO] Filled definition %uuid% with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] ...remembering this script entry as 'has_ban'!
16:36:23 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT count(*) FROM playerbans WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599' AND unbanned='0';'
16:36:23 [INFO] Running query SELECT count(*) FROM playerbans WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599' AND unbanned='0';
16:36:23 [INFO] Got a query result of 1 columns
16:36:23 [INFO] Got a query result of 1 rows
16:36:23 [INFO] Queue 'CHECK_SQL_BAN_STATE_WorryGrassRisks' Executing:
ANNOUNCE "<entry[has_ban].result.get[1].split[/].get[1]>"
16:36:23 [INFO] +- Executing dCommand: ANNOUNCE/p@WizardsMine ---------+
16:36:23 [INFO] Filled tag <entry[has_ban].result.get[1].split[/].get[1]>
with '1'.
16:36:23 [INFO] +> Executing 'ANNOUNCE': Message='1' Type='ALL'
16:36:23 [INFO] Queue 'CHECK_SQL_BAN_STATE_WorryGrassRisks' Executing: IF
"<entry[has_ban].result.get[1].split[/].get[1]>" ">=" "1" "{"
"-" "determine" "true" "}"
16:36:23 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'IF': use_braces='true'
16:36:23 [INFO] Filled tag <entry[has_ban].result.get[1].split[/].get[1]>
with '1'.
16:36:23 [INFO] Comparable='Decimal(1.0)', Operator='OR_MORE',
ComparedTo='Decimal(1.0)' --> OUTCOME='true'
16:36:23 [INFO] Queue 'CHECK_SQL_BAN_STATE_WorryGrassRisks' Executing:
DETERMINE "true"
16:36:23 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'DETERMINE': outcome='true' passively='false'
16:36:23 [INFO] Completing queue 'CHECK_SQL_BAN_STATE_WorryGrassRisks' in
107ms.
16:36:23 [INFO] Filled tag
<proc[check_sql_ban_state].context[7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'true'.
16:36:23 [INFO] Comparable='Element(true)', Operator='EQUALS',
ComparedTo='Element(true)' --> OUTCOME='true'
16:36:23 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: SQL
"id:mcserver" "query:SELECT * FROM playerbans WHERE
uuid='%uuid%' AND unbanned='0' ORDER BY ban_expire;"
"save:bans"
16:36:23 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:23 [INFO] ERROR in script 'SET_BAN_STATE'! Unknown definition %uuid%.
16:36:23 [INFO] +> [Denizen] (Attempted: SQL "id:mcserver" "query:SELECT *
FROM playerbans WHERE uuid='%uuid%' AND unbanned='0' ORDER BY
ban_expire;" "save:bans")
16:36:23 [INFO] Filled definition %uuid% with 'null'.
16:36:23 [INFO] ...remembering this script entry as 'bans'!
16:36:23 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT * FROM playerbans WHERE uuid='null' AND
unbanned='0' ORDER BY ban_expire;'
16:36:23 [INFO] Running query SELECT * FROM playerbans WHERE uuid='null' AND
unbanned='0' ORDER BY ban_expire;
16:36:23 [INFO] Forcing queue SET_BAN_STATE_PokemonPestConcerns into a timed
queue...
16:36:23 [INFO] Completing queue 'SET_BAN_STATE_PokemonPestConcerns' in
205ms.
16:36:23 [INFO] Starting TimedQueue 'SET_BAN_STATE_PokemonPestConcerns'...
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: IF
"<yaml[bans].contains[players.<pl>]>" "{" "-" "determine"
"KICKED:You are banned from Wizardsmine
until:<&nl><yaml[bans].read[players.<pl>.expire]><&nl>Reason<&co>
<yaml[bans].read[players.<pl>.reason]>!" "}"
16:36:23 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'IF': use_braces='true'
16:36:23 [INFO] Filled tag <pl> with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:23 [INFO] Filled tag
<yaml[bans].contains[players.p@7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'false'.
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: WAIT "1"
16:36:23 [INFO] +- Executing dCommand: WAIT/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'WAIT': queue='GLOBAL_LOGIN_ComplyRadiosFusion'
delay='d@1.0s'
16:36:23 [INFO] Forcing queue GLOBAL_LOGIN_ComplyRadiosFusion into a timed
queue...
16:36:23 [INFO] Completing queue 'GLOBAL_LOGIN_ComplyRadiosFusion' in 281ms.
16:36:23 [INFO] Starting TimedQueue 'GLOBAL_LOGIN_ComplyRadiosFusion'...
16:36:23 [INFO] Running script event 'PlayerJoins', event='player joins' for
script 'GLOBAL_LOGIN'
16:36:23 [INFO] Starting InstantQueue 'GLOBAL_LOGIN_PursueRentalsStop'...
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_PursueRentalsStop' Executing: DETERMINE
"passively" ""
16:36:23 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'DETERMINE': outcome='' passively='true'
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_PursueRentalsStop' Executing: WAIT "1t"
16:36:23 [INFO] +- Executing dCommand: WAIT/p@WizardsMine ---------+
16:36:23 [INFO] +> Executing 'WAIT': queue='GLOBAL_LOGIN_PursueRentalsStop'
delay='d@0.05s'
16:36:23 [INFO] Forcing queue GLOBAL_LOGIN_PursueRentalsStop into a timed
queue...
16:36:23 [INFO] Completing queue 'GLOBAL_LOGIN_PursueRentalsStop' in 3ms.
16:36:23 [INFO] Starting TimedQueue 'GLOBAL_LOGIN_PursueRentalsStop'...
16:36:23 [INFO] Queue 'GLOBAL_LOGIN_PursueRentalsStop' Executing: RUN
"show_loading_inventory" "def:<pl>" "instantly"
16:36:23 [INFO] +- Executing dCommand: RUN/p@WizardsMine ---------+
16:36:24 [INFO] Filled tag <pl> with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:24 [INFO] +> Executing 'RUN': script='SHOW_LOADING_INVENTORY(TASK)'
instant='true'
definitions='li@p@7af2ce60-44c7-42db-b793-fbfd2879e599'
16:36:24 [INFO] Adding definition %player% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Filled tag <red> with ''.
16:36:24 [INFO] Completing queue 'GLOBAL_LOGIN_PursueRentalsStop' in 1146ms.
16:36:25 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: IF
"<pl.is_online>" "{" "-" "run" "load_player_data"
"def:<pl>|<context.hostname.after[/]>" "instantly" "-" "flag"
"player" "login_procedure:!" "-" "flag" "player"
"login_time:<server.current_time_millis.div_int[1000]>" "}"
"else" "{" "-" "wait" "5" "-" "announce" "<red>Player is not
online" "}"
16:36:25 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'IF': use_braces='true'
16:36:25 [INFO] Filled tag <pl.is_online> with 'true'.
16:36:25 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: RUN
"load_player_data" "def:<pl>|<context.hostname.after[/]>"
"instantly"
16:36:25 [INFO] +- Executing dCommand: RUN/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <pl> with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Filled tag <context.hostname.after[/]> with '127.0.0.1'.
16:36:25 [INFO] +> Executing 'RUN': script='LOAD_PLAYER_DATA(TASK)'
instant='true'
definitions='li@p@7af2ce60-44c7-42db-b793-fbfd2879e599|127.0.0.1'
16:36:25 [INFO] Adding definition %player% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Adding definition %host% as 127.0.0.1
16:36:25 [INFO] Starting InstantQueue 'LOAD_PLAYER_DATA_HawkAxisBaths'...
16:36:25 [INFO] Queue 'LOAD_PLAYER_DATA_HawkAxisBaths' Executing: IF
"<proc[player_exists].context[<def[player].uuid>]>" "=="
"false" "{" "-" "run" "insert_new_player"
"def:<def[player].uuid>|%host%" "instantly" "-" "run"
"load_default_player_data" "def:%player%" "instantly" "}"
"else" "{" "-" "define" "pd"
"<proc[get_player_data].context[<def[player].uuid>]>" "-"
"yaml" "id:pd" "set"
"players.%player%.playtime:<def[pd].get[3]>" "-" "run"
"set_player_permissions" "def:%player%" "instantly" "}"
16:36:25 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'IF': use_braces='true'
16:36:25 [INFO] Filled tag <def[player].uuid> with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Adding definition %uuid% as
7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Starting InstantQueue
'PLAYER_EXISTS_PortableWorryEntities'...
16:36:25 [INFO] Queue 'PLAYER_EXISTS_PortableWorryEntities' Executing: SQL
"id:mcserver" "query:SELECT count(*) FROM playerdata WHERE
uuid='%uuid%';" "save:is_new"
16:36:25 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %uuid% with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] ...remembering this script entry as 'is_new'!
16:36:25 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT count(*) FROM playerdata WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';'
16:36:25 [INFO] Running query SELECT count(*) FROM playerdata WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';
16:36:25 [INFO] Got a query result of 1 columns
16:36:25 [INFO] Got a query result of 1 rows
16:36:25 [INFO] Queue 'PLAYER_EXISTS_PortableWorryEntities' Executing: IF
"<entry[is_new].result.get[1].split[/].get[1]>" ">=" "1" "{"
"-" "determine" "true" "}"
16:36:25 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'IF': use_braces='true'
16:36:25 [INFO] Filled tag <entry[is_new].result.get[1].split[/].get[1]> with
'1'.
16:36:25 [INFO] Comparable='Decimal(1.0)', Operator='OR_MORE',
ComparedTo='Decimal(1.0)' --> OUTCOME='true'
16:36:25 [INFO] Queue 'PLAYER_EXISTS_PortableWorryEntities' Executing:
DETERMINE "true"
16:36:25 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'DETERMINE': outcome='true' passively='false'
16:36:25 [INFO] Completing queue 'PLAYER_EXISTS_PortableWorryEntities' in
74ms.
16:36:25 [INFO] Filled tag
<proc[player_exists].context[7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'true'.
16:36:25 [INFO] Comparable='Element(true)', Operator='EQUALS',
ComparedTo='Element(false)' --> OUTCOME='false'
16:36:25 [INFO] Queue 'LOAD_PLAYER_DATA_HawkAxisBaths' Executing: DEFINE "pd"
"<proc[get_player_data].context[<def[player].uuid>]>"
16:36:25 [INFO] +- Executing dCommand: DEFINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <def[player].uuid> with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Adding definition %uuid% as
7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Starting InstantQueue 'GET_PLAYER_DATA_SuspendedBlockNeed'...
16:36:25 [INFO] Queue 'GET_PLAYER_DATA_SuspendedBlockNeed' Executing: SQL
"id:mcserver" "query:SELECT * FROM playerdata WHERE
uuid='%uuid%';" "save:pd_result"
16:36:25 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %uuid% with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] ...remembering this script entry as 'pd_result'!
16:36:25 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT * FROM playerdata WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';'
16:36:25 [INFO] Running query SELECT * FROM playerdata WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';
16:36:25 [INFO] Got a query result of 7 columns
16:36:25 [INFO] Got a query result of 1 rows
16:36:25 [INFO] Queue 'GET_PLAYER_DATA_SuspendedBlockNeed' Executing:
DETERMINE "<entry[pd_result].result.get[1].split[/]>"
16:36:25 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <entry[pd_result].result.get[1].split[/]> with
'li@4|7af2ce60-44c7-42db-b793-fbfd2879e599|14491|127&dot0&dot0&dot1|1466530490|1466606173|server&atsurvival'.
16:36:25 [INFO] +> Executing 'DETERMINE':
outcome='li@4|7af2ce60-44c7-42db-b793-fbfd2879e599|14491|127&dot0&dot0&dot1|1466530490|1466606173|server&atsurvival'
passively='false'
16:36:25 [INFO] Completing queue 'GET_PLAYER_DATA_SuspendedBlockNeed' in
85ms.
16:36:25 [INFO] Filled tag
<proc[get_player_data].context[7af2ce60-44c7-42db-b793-fbfd2879e599]>
with
'li@4|7af2ce60-44c7-42db-b793-fbfd2879e599|14491|127&dot0&dot0&dot1|1466530490|1466606173|server&atsurvival'.
16:36:25 [INFO] +> Executing 'DEFINE': queue='LOAD_PLAYER_DATA_HawkAxisBaths'
definition='pd'
value='li@4|7af2ce60-44c7-42db-b793-fbfd2879e599|14491|127&dot0&dot0&dot1|1466530490|1466606173|server&atsurvival'
16:36:25 [INFO] Queue 'LOAD_PLAYER_DATA_HawkAxisBaths' Executing: YAML
"id:pd" "set" "players.%player%.playtime:<def[pd].get[3]>"
16:36:25 [INFO] +- Executing dCommand: YAML/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Filled tag <def[pd].get[3]> with '14491'.
16:36:25 [INFO] +> Executing 'YAML': id='pd' action='SET'
yaml_action='SET_VALUE'
key='players.p@7af2ce60-44c7-42db-b793-fbfd2879e599.playtime'
value='14491' fix_formatting='false'
16:36:25 [INFO] Queue 'LOAD_PLAYER_DATA_HawkAxisBaths' Executing: RUN
"set_player_permissions" "def:%player%" "instantly"
16:36:25 [INFO] +- Executing dCommand: RUN/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] +> Executing 'RUN': script='SET_PLAYER_PERMISSIONS(TASK)'
instant='true'
definitions='li@p@7af2ce60-44c7-42db-b793-fbfd2879e599'
16:36:25 [INFO] Adding definition %player% as
p@7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Starting InstantQueue
'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'...
16:36:25 [INFO] Queue 'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
Executing: DEFINE "rank_permissions"
"<proc[get_player_rank_permissions].context[<def[player].uuid>]>"
16:36:25 [INFO] +- Executing dCommand: DEFINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <def[player].uuid> with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Adding definition %uuid% as
7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Starting InstantQueue
'GET_PLAYER_RANK_PERMISSIONS_ReductionsDpiBlend'...
16:36:25 [INFO] Queue 'GET_PLAYER_RANK_PERMISSIONS_ReductionsDpiBlend'
Executing: SQL "id:mcserver" "query:SELECT p.perm_key FROM
permissions p, rank_permissions rp, playerranks pr WHERE
pr.rank_id=rp.rank_id AND p.id=rp.permission_id AND
pr.uuid='%uuid%';" "save:permissions"
16:36:25 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %uuid% with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] ...remembering this script entry as 'permissions'!
16:36:25 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT p.perm_key FROM permissions p, rank_permissions
rp, playerranks pr WHERE pr.rank_id=rp.rank_id AND
p.id=rp.permission_id AND
pr.uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';'
16:36:25 [INFO] Running query SELECT p.perm_key FROM permissions p,
rank_permissions rp, playerranks pr WHERE
pr.rank_id=rp.rank_id AND p.id=rp.permission_id AND
pr.uuid='7af2ce60-44c7-42db-b793-fbfd2879e599';
16:36:25 [INFO] Got a query result of 1 columns
16:36:25 [INFO] Got a query result of 0 rows
16:36:25 [INFO] Queue 'GET_PLAYER_RANK_PERMISSIONS_ReductionsDpiBlend'
Executing: DETERMINE
"<entry[permissions].result.deduplicate||li@>"
16:36:25 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <entry[permissions].result.deduplicate||li@> with
'li@'.
16:36:25 [INFO] +> Executing 'DETERMINE': outcome='li@' passively='false'
16:36:25 [INFO] Completing queue
'GET_PLAYER_RANK_PERMISSIONS_ReductionsDpiBlend' in 41ms.
16:36:25 [INFO] Filled tag
<proc[get_player_rank_permissions].context[7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'li@'.
16:36:25 [INFO] +> Executing 'DEFINE':
queue='SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
definition='rank_permissions' value='li@'
16:36:25 [INFO] Queue 'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
Executing: DEFINE "player_permissions"
"<proc[get_player_permissions].context[<def[player].uuid>]>"
16:36:25 [INFO] +- Executing dCommand: DEFINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <def[player].uuid> with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] Adding definition %uuid% as
7af2ce60-44c7-42db-b793-fbfd2879e599
16:36:25 [INFO] Starting InstantQueue
'GET_PLAYER_PERMISSIONS_CharterCitizensPuzzles'...
16:36:25 [INFO] Queue 'GET_PLAYER_PERMISSIONS_CharterCitizensPuzzles'
Executing: SQL "id:mcserver" "query:SELECT p.perm_key FROM
permissions p, playerperms pp WHERE uuid='%uuid%' AND
pp.perm_id=p.id;" "save:permissions"
16:36:25 [INFO] +- Executing dCommand: SQL/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %uuid% with
'7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] ...remembering this script entry as 'permissions'!
16:36:25 [INFO] +> Executing 'SQL': sqlid='mcserver' action='QUERY'
query='SELECT p.perm_key FROM permissions p, playerperms pp
WHERE uuid='7af2ce60-44c7-42db-b793-fbfd2879e599' AND
pp.perm_id=p.id;'
16:36:25 [INFO] Running query SELECT p.perm_key FROM permissions p,
playerperms pp WHERE
uuid='7af2ce60-44c7-42db-b793-fbfd2879e599' AND
pp.perm_id=p.id;
16:36:25 [INFO] Got a query result of 1 columns
16:36:25 [INFO] Got a query result of 0 rows
16:36:25 [INFO] Queue 'GET_PLAYER_PERMISSIONS_CharterCitizensPuzzles'
Executing: DETERMINE
"<entry[permissions].result.deduplicate||li@>"
16:36:25 [INFO] +- Executing dCommand: DETERMINE/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <entry[permissions].result.deduplicate||li@> with
'li@'.
16:36:25 [INFO] +> Executing 'DETERMINE': outcome='li@' passively='false'
16:36:25 [INFO] Completing queue
'GET_PLAYER_PERMISSIONS_CharterCitizensPuzzles' in 40ms.
16:36:25 [INFO] Filled tag
<proc[get_player_permissions].context[7af2ce60-44c7-42db-b793-fbfd2879e599]>
with 'li@'.
16:36:25 [INFO] +> Executing 'DEFINE':
queue='SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
definition='player_permissions' value='li@'
16:36:25 [INFO] Queue 'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
Executing: YAML "id:pd" "set"
"players.%player%.permissions:->:ranks.guest"
16:36:25 [INFO] +- Executing dCommand: YAML/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %player% with
'p@7af2ce60-44c7-42db-b793-fbfd2879e599'.
16:36:25 [INFO] +> Executing 'YAML': id='pd' action='SET'
yaml_action='INSERT'
key='players.p@7af2ce60-44c7-42db-b793-fbfd2879e599.permissions'
value='ranks.guest' fix_formatting='false'
16:36:25 [INFO] Queue 'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
Executing: FOREACH "%rank_permissions%" "{" "-" "if"
"!<yaml[pd].read[players.%player%.permissions].contains[<def[value].split[/].get[1].unescaped>]||false>"
"{" "-" "yaml" "id:pd" "set"
"players.%player%.permissions:->:<def[value].split[/].get[1].unescaped>"
"}" "}"
16:36:25 [INFO] +- Executing dCommand: FOREACH/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %rank_permissions% with 'li@'.
16:36:25 [INFO] +> Executing 'FOREACH': list='li@'
16:36:25 [INFO] Empty list, not looping...
16:36:25 [INFO] Queue 'SET_PLAYER_PERMISSIONS_FranceReactionsMaria'
Executing: FOREACH "%player_permissions%" "{" "-" "if"
"!<yaml[pd].read[players.%player%.permissions].contains[<def[value].split[/].get[1].unescaped>]||false>"
"{" "-" "yaml" "id:pd" "set"
"players.%player%.permissions:->:<def[value].split[/].get[1].unescaped>"
"}" "}"
16:36:25 [INFO] +- Executing dCommand: FOREACH/p@WizardsMine ---------+
16:36:25 [INFO] Filled definition %player_permissions% with 'li@'.
16:36:25 [INFO] +> Executing 'FOREACH': list='li@'
16:36:25 [INFO] Empty list, not looping...
16:36:25 [INFO] Completing queue
'SET_PLAYER_PERMISSIONS_FranceReactionsMaria' in 127ms.
16:36:25 [INFO] Queue 'LOAD_PLAYER_DATA_HawkAxisBaths' Executing: YAML
"savefile:playerdata/playerdata.yml" "id:pd"
16:36:25 [INFO] +- Executing dCommand: YAML/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'YAML': id='pd' action='SAVE'
filename='playerdata/playerdata.yml' value=''
fix_formatting='false'
16:36:25 [INFO] Completing queue 'LOAD_PLAYER_DATA_HawkAxisBaths' in 426ms.
16:36:25 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: FLAG
"player" "login_procedure:!"
16:36:25 [INFO] +- Executing dCommand: FLAG/p@WizardsMine ---------+
16:36:25 [INFO] +> Executing 'FLAG': flag_name='LOGIN_PROCEDURE'
Action/Value='DELETE(false)' flag_target='p@WizardsMine'
16:36:25 [INFO] Queue 'GLOBAL_LOGIN_ComplyRadiosFusion' Executing: FLAG
"player"
"login_time:<server.current_time_millis.div_int[1000]>"
16:36:25 [INFO] +- Executing dCommand: FLAG/p@WizardsMine ---------+
16:36:25 [INFO] Filled tag <server.current_time_millis.div_int[1000]> with
'1466606185'.
16:36:25 [INFO] +> Executing 'FLAG': flag_name='LOGIN_TIME'
Action/Value='SET_VALUE(1466606185)'
flag_target='p@WizardsMine'
16:36:25 [INFO] Completing queue 'GLOBAL_LOGIN_ComplyRadiosFusion' in 2277ms.
16:36:25 [INFO] Filled tag <green> with ''.
16:36:25 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:26 [INFO] Got a query result of 11 columns
16:36:26 [INFO] Got a query result of 0 rows
16:36:26 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: FOREACH
"<entry[bans].result||li@>" "{" "-" "if"
"<def[value].split[/].get[6]>" "==" "1" "{" "-" "define"
"ban_expire" "<def[value].split[/].get[6]>" "-" "define"
"reason" "<def[value].split[/].get[7]>" "-" "foreach" "stop"
"}" "-" "if" "<def[ban_expire]||0>" "!=" "1" "&&"
"<def[ban_expire]||0>" "<" "<def[value].split[/].get[6]>" "{"
"-" "define" "ban_expire" "<def[value].split[/].get[1]>" "-"
"define" "reason" "<def[value].split[/].get[7]>" "}" "}"
16:36:26 [INFO] +- Executing dCommand: FOREACH/p@WizardsMine ---------+
16:36:26 [INFO] Filled tag <entry[bans].result||li@> with 'li@'.
16:36:26 [INFO] +> Executing 'FOREACH': list='li@'
16:36:26 [INFO] Empty list, not looping...
16:36:26 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: IF
"<def[ban_expire]||0>" "!=" "0" "{" "-" "yaml" "id:bans" "set"
"players.%player%.expire:<def[ban_expire]>" "-" "yaml"
"id:bans" "set" "players.%player%.reason:<def[reason]>" "}"
16:36:26 [INFO] +- Executing dCommand: IF/p@WizardsMine ---------+
16:36:26 [INFO] +> Executing 'IF': use_braces='true'
16:36:26 [INFO] Filled tag <def[ban_expire]||0> with '0'.
16:36:26 [INFO] Logic='NEGATIVE', Comparable='Decimal(0.0)',
Operator='EQUALS', ComparedTo='Decimal(0.0)' -->
OUTCOME='false'
16:36:26 [INFO] Queue 'SET_BAN_STATE_PokemonPestConcerns' Executing: YAML
"id:bans" "savefile:playerdata/bans.yml"
16:36:26 [INFO] +- Executing dCommand: YAML/p@WizardsMine ---------+
16:36:26 [INFO] +> Executing 'YAML': id='bans' action='SAVE'
filename='playerdata/bans.yml' value=''
fix_formatting='false'
16:36:26 [INFO] Completing queue 'SET_BAN_STATE_PokemonPestConcerns' in
2870ms.
16:36:26 [INFO] Filled tag <green> with ''.
16:36:26 [INFO] Filled tag <green> with ''.
16:36:26 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:27 [INFO] Queue 'GLOBAL_LOGIN_RnDpiFootball' Executing: IF
"<context.uuid.as_player.has_flag[login_procedure].not>" "&&"
"<context.uuid.as_player.is_online>" "{" "-" "define"
"player_name" "<context.name>" "-" "bungee"
"<bungee.list_servers>" "{" "-" "announce"
"<yellow>%player_name% joined the network!" "}" "}"
16:36:27 [INFO] +- Executing dCommand: IF ---------+
16:36:27 [INFO] +> Executing 'IF': use_braces='true'
16:36:27 [INFO] Filled tag
<context.uuid.as_player.has_flag[login_procedure].not> with
'true'.
16:36:27 [INFO] Filled tag <context.uuid.as_player.is_online> with 'true'.
16:36:27 [INFO] Queue 'GLOBAL_LOGIN_RnDpiFootball' Executing: DEFINE
"player_name" "<context.name>"
16:36:27 [INFO] +- Executing dCommand: DEFINE ---------+
16:36:27 [INFO] Filled tag <context.name> with 'WizardsMine'.
16:36:27 [INFO] +> Executing 'DEFINE': queue='GLOBAL_LOGIN_RnDpiFootball'
definition='player_name' value='WizardsMine'
16:36:27 [INFO] Queue 'GLOBAL_LOGIN_RnDpiFootball' Executing: BUNGEE
"<bungee.list_servers>" "{" "-" "announce"
"<yellow>%player_name% joined the network!" "}"
16:36:27 [INFO] +- Executing dCommand: BUNGEE ---------+
16:36:27 [INFO] Filled tag <bungee.list_servers> with
'li@server@lobby|server@survival'.
16:36:27 [INFO] +> Executing 'BUNGEE': servers='li@lobby|survival'
16:36:27 [INFO] Starting InstantQueue 'BUNGEE_CMD_MemberRainIs'...
16:36:27 [INFO] Queue 'BUNGEE_CMD_MemberRainIs' Executing: ANNOUNCE
"<yellow>%player_name% joined the network!"
16:36:27 [INFO] +- Executing dCommand: ANNOUNCE ---------+
16:36:27 [INFO] Filled definition %player_name% with 'WizardsMine'.
16:36:27 [INFO] Completing queue 'GLOBAL_LOGIN_RnDpiFootball' in 4520ms.
16:36:27 [INFO] Filled tag <green> with ''.
16:36:27 [INFO] Filled tag <yellow> with ''.
16:36:27 [INFO] +> Executing 'ANNOUNCE': Message='WizardsMine joined the
network!' Type='ALL'
16:36:27 [INFO] Completing queue 'BUNGEE_CMD_MemberRainIs' in 37ms.
16:36:27 [INFO] Filled tag <green> with ''.
16:36:27 [INFO] Filled tag <green> with ''.
16:36:27 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <green> with ''.
16:36:28 [INFO] Filled tag <green> with ''.
16:36:28 [INFO] Filled tag <green> with ''.
16:36:28 [INFO] Filled tag <green> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:28 [INFO] Filled tag <red> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:29 [INFO] Filled tag <red> with ''.
16:36:29 [INFO] Filled tag <red> with ''.
16:36:29 [INFO] Filled tag <red> with ''.
16:36:29 [INFO] Filled tag <red> with ''.
16:36:29 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <green> with ''.
16:36:30 [INFO] Filled tag <red> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.
16:36:31 [INFO] Filled tag <green> with ''.