WORLDEDIT-3530 - Reported by unenergizer

When a player logs in that is not OP, the console is shows a worldEdit error. This server has only 5 plugins, two which are mine and don't conflict with world edit. There is no permission plugin on my server. When a player logs into my server, I change their game mode to AdventureMode (gamemode 2).

Here is the log:
[11:35:35 INFO]: MinePile[/127.0.0.1:54863] logged in with entity id 221 at ([wo
rld]-2.5, 103.0, -4.5)
[11:35:35 ERROR]: Could not pass event PlayerGameModeChangeEvent to WorldEdit v6
.1.3;7a097ca
org.bukkit.event.EventException
at org.bukkit.plugin.java.JavaPluginLoader$1.execute(JavaPluginLoader.ja
va:306) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.RegisteredListener.callEvent(RegisteredListener.jav
a:62) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.SimplePluginManager.fireEvent(SimplePluginManager.j
ava:502) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.SimplePluginManager.callEvent(SimplePluginManager.j
ava:487) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.craftbukkit.v1_11_R1.entity.CraftPlayer.setGameMode(CraftP
layer.java:788) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at com.forgestorm.hub.player.SetupHubPlayer.run(SetupHubPlayer.java:46)
[forgestorm-hub-0.0.1-SNAPSHOT.jar:?]
at org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftTask.run(CraftTask.jav
a:71) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftScheduler.mainThreadHe
artbeat(CraftScheduler.java:353) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.D(MinecraftServer.java:
730) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.DedicatedServer.D(DedicatedServer.java:
399) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.C(MinecraftServer.java:
675) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.run(MinecraftServer.jav
a:574) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_111]
Caused by: java.lang.UnsupportedOperationException: Not supported yet.
at de.bananaco.bpermissions.api.ApiLayer.hasPermission(ApiLayer.java:41)
~[?:?]
at com.sk89q.wepif.bPermissionsResolver.hasPermission(bPermissionsResolv
er.java:63) ~[?:?]
at com.sk89q.wepif.bPermissionsResolver.hasPermission(bPermissionsResolv
er.java:88) ~[?:?]
at com.sk89q.wepif.PermissionsResolverManager.hasPermission(PermissionsR
esolverManager.java:173) ~[?:?]
at com.sk89q.worldedit.bukkit.BukkitPlayer.hasPermission(BukkitPlayer.ja
va:144) ~[?:?]
at com.sk89q.worldedit.session.SessionManager.get(SessionManager.java:16
8) ~[?:?]
at com.sk89q.worldedit.WorldEdit.getSession(WorldEdit.java:210) ~[?:?]
at com.sk89q.worldedit.bukkit.WorldEditListener.onGamemode(WorldEditList
ener.java:70) ~[?:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0
_111]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.
java:62) ~[?:1.8.0_111]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
sorImpl.java:43) ~[?:1.8.0_111]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_111]
at org.bukkit.plugin.java.JavaPluginLoader$1.execute(JavaPluginLoader.ja
va:302) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
... 12 more

Comment by unenergizer

The only plugins I use besides WorldEdit is TitleManager and Citizens. Those may have the API in them, I have not investigated. My projects do not have any permission system and very limited on what API's are in the project. I have 4 years experience programming and I did not make my software based on some youtube tutorial. So I promise, it is not my code.

Basically when a player joines, I open a async thread and wait on redis to pull down data to build my players profiles. Once that data is loaded, I cancel the thread, and finish the setup on the player. After the thread is canceled is when the player's gamemode is changed. That being said, I don't feel this is directly related to the issue at hand. So out of the 5 plugins, the other 3 are the only ones that could contain a permission system, because it does not exist on my machine nor have I downloaded a permission system.

I would be willing to let someone spectate my computer to see for themselves. Cheers!

Comment by wizjany

eh, i don't really care enough to find out what's triggering that to happen, as long as disabling the resolver works for you then it's fine.

Comment by wizjany

delete wepif.yml in your server root directory. if that doesn't work, pastebin your server.log file please.

Comment by unenergizer

Wizjany, I found that if I put all resolvers in the disabled configuration section, the plugin works without errors! However, I will post the stack trace for you anyway. I found that the server thinks I am using bpermissions, which I am not. wepif.yml has never been edited before today. And honestly, this problem didn't happen until I updated all my source code and server to 1.11.

[20:35:50] [Server thread/INFO]: Starting minecraft server version 1.11
[20:35:50] [Server thread/INFO]: Loading properties
[20:35:50] [Server thread/INFO]: Default game type: SURVIVAL
[20:35:51] [Server thread/INFO]: This server is running CraftBukkit version git-Spigot-f950f8e-4d99fed (MC: 1.11) (Implementing API version 1.11-R0.1-SNAPSHOT)
[20:35:51] [Server thread/INFO]: Debug logging is disabled
[20:35:51] [Server thread/INFO]: Server Ping Player Sample Count: 12
[20:35:51] [Server thread/INFO]: Using 4 threads for Netty based IO
[20:35:51] [Server thread/INFO]: Generating keypair
[20:35:51] [Server thread/INFO]: Starting Minecraft server on 127.0.0.1:25566
[20:35:51] [Server thread/INFO]: Using default channel type
[20:35:51] [Server thread/INFO]: Set PluginClassLoader as parallel capable
[20:35:51] [Server thread/INFO]: [TitleManager] Loading TitleManager v1.5.13
[20:35:51] [Server thread/INFO]: [Citizens] Loading Citizens v2.0.21-SNAPSHOT (build 1445)
[20:35:51] [Server thread/INFO]: [WorldEdit] Loading WorldEdit v6.1.3;7a097ca
[20:35:51] [Server thread/INFO]: [FS-SpigotCore] Loading FS-SpigotCore v0.1
[20:35:51] [Server thread/INFO]: [FS-Hub] Loading FS-Hub v0.1
[20:35:51] [Server thread/WARN]: **** SERVER IS RUNNING IN OFFLINE/INSECURE MODE!
[20:35:51] [Server thread/WARN]: The server will make no attempt to authenticate usernames. Beware.
[20:35:51] [Server thread/WARN]: Whilst this makes it possible to use BungeeCord, unless access to your server is properly restricted, it also opens up the ability for hackers to connect with any username they choose.
[20:35:51] [Server thread/WARN]: Please see http://www.spigotmc.org/wiki/firewall-guide/ for further information.
[20:35:51] [Server thread/WARN]: To change this, set "online-mode" to "true" in the server.properties file.
[20:35:51] [Server thread/INFO]: **** Beginning UUID conversion, this may take A LONG time ****
[20:35:51] [Server thread/INFO]: Preparing level "world"
[20:35:51] [Server thread/INFO]: -------- World Settings For [world] --------
[20:35:51] [Server thread/INFO]: Item Merge Radius: 2.5
[20:35:51] [Server thread/INFO]: Item Despawn Rate: 6000
[20:35:51] [Server thread/INFO]: Experience Merge Radius: 3.0
[20:35:51] [Server thread/INFO]: Zombie Aggressive Towards Villager: true
[20:35:51] [Server thread/INFO]: Arrow Despawn Rate: 1200
[20:35:51] [Server thread/INFO]: Allow Zombie Pigmen to spawn from portal blocks: true
[20:35:51] [Server thread/INFO]: Nerfing mobs spawned from spawners: false
[20:35:51] [Server thread/INFO]: View Distance: 10
[20:35:51] [Server thread/INFO]: Custom Map Seeds: Village: 10387312 Feature: 14357617
[20:35:51] [Server thread/INFO]: Hopper Transfer: 8 Hopper Check: 8 Hopper Amount: 1
[20:35:51] [Server thread/INFO]: Entity Tracking Range: Pl 48 / An 48 / Mo 48 / Mi 32 / Other 64
[20:35:51] [Server thread/INFO]: Mob Spawn Range: 4
[20:35:51] [Server thread/INFO]: Cactus Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cane Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Melon Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Mushroom Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Pumpkin Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Sapling Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Wheat Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: NetherWart Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Vine Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cocoa Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Structure Info Saving: true
[20:35:51] [Server thread/INFO]: Random Lighting Updates: false
[20:35:51] [Server thread/INFO]: Entity Activation Range: An 32 / Mo 32 / Mi 16
[20:35:51] [Server thread/INFO]: Max TNT Explosions: 100
[20:35:51] [Server thread/INFO]: Tile Max Tick Time: 50ms Entity max Tick Time: 50ms
[20:35:51] [Server thread/INFO]: -------- World Settings For [world_nether] --------
[20:35:51] [Server thread/INFO]: Item Merge Radius: 2.5
[20:35:51] [Server thread/INFO]: Item Despawn Rate: 6000
[20:35:51] [Server thread/INFO]: Experience Merge Radius: 3.0
[20:35:51] [Server thread/INFO]: Zombie Aggressive Towards Villager: true
[20:35:51] [Server thread/INFO]: Arrow Despawn Rate: 1200
[20:35:51] [Server thread/INFO]: Allow Zombie Pigmen to spawn from portal blocks: true
[20:35:51] [Server thread/INFO]: Nerfing mobs spawned from spawners: false
[20:35:51] [Server thread/INFO]: View Distance: 10
[20:35:51] [Server thread/INFO]: Custom Map Seeds: Village: 10387312 Feature: 14357617
[20:35:51] [Server thread/INFO]: Hopper Transfer: 8 Hopper Check: 8 Hopper Amount: 1
[20:35:51] [Server thread/INFO]: Entity Tracking Range: Pl 48 / An 48 / Mo 48 / Mi 32 / Other 64
[20:35:51] [Server thread/INFO]: Mob Spawn Range: 4
[20:35:51] [Server thread/INFO]: Cactus Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cane Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Melon Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Mushroom Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Pumpkin Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Sapling Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Wheat Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: NetherWart Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Vine Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cocoa Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Structure Info Saving: true
[20:35:51] [Server thread/INFO]: Random Lighting Updates: false
[20:35:51] [Server thread/INFO]: Entity Activation Range: An 32 / Mo 32 / Mi 16
[20:35:51] [Server thread/INFO]: Max TNT Explosions: 100
[20:35:51] [Server thread/INFO]: Tile Max Tick Time: 50ms Entity max Tick Time: 50ms
[20:35:51] [Server thread/INFO]: -------- World Settings For [world_the_end] --------
[20:35:51] [Server thread/INFO]: Item Merge Radius: 2.5
[20:35:51] [Server thread/INFO]: Item Despawn Rate: 6000
[20:35:51] [Server thread/INFO]: Experience Merge Radius: 3.0
[20:35:51] [Server thread/INFO]: Zombie Aggressive Towards Villager: true
[20:35:51] [Server thread/INFO]: Arrow Despawn Rate: 1200
[20:35:51] [Server thread/INFO]: Allow Zombie Pigmen to spawn from portal blocks: true
[20:35:51] [Server thread/INFO]: Nerfing mobs spawned from spawners: false
[20:35:51] [Server thread/INFO]: View Distance: 10
[20:35:51] [Server thread/INFO]: Custom Map Seeds: Village: 10387312 Feature: 14357617
[20:35:51] [Server thread/INFO]: Hopper Transfer: 8 Hopper Check: 8 Hopper Amount: 1
[20:35:51] [Server thread/INFO]: Entity Tracking Range: Pl 48 / An 48 / Mo 48 / Mi 32 / Other 64
[20:35:51] [Server thread/INFO]: Mob Spawn Range: 4
[20:35:51] [Server thread/INFO]: Cactus Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cane Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Melon Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Mushroom Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Pumpkin Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Sapling Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Wheat Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: NetherWart Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Vine Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Cocoa Growth Modifier: 100%
[20:35:51] [Server thread/INFO]: Structure Info Saving: true
[20:35:51] [Server thread/INFO]: Random Lighting Updates: false
[20:35:51] [Server thread/INFO]: Entity Activation Range: An 32 / Mo 32 / Mi 16
[20:35:51] [Server thread/INFO]: Max TNT Explosions: 100
[20:35:51] [Server thread/INFO]: Tile Max Tick Time: 50ms Entity max Tick Time: 50ms
[20:35:51] [Server thread/INFO]: Preparing start region for level 0 (Seed: -8956016945560985824)
[20:35:53] [Server thread/INFO]: Preparing spawn area: 50%
[20:35:53] [Server thread/INFO]: Preparing start region for level 1 (Seed: 3125776507270254404)
[20:35:54] [Server thread/INFO]: Preparing start region for level 2 (Seed: 3125776507270254404)
[20:35:54] [Server thread/INFO]: [TitleManager] Enabling TitleManager v1.5.13
[20:35:54] [Server thread/WARN]: [TitleManager] Vault is not enabled! Disabling permissions and economy based variables!
[20:35:54] [Server thread/INFO]: [Citizens] Enabling Citizens v2.0.21-SNAPSHOT (build 1445)
[20:35:54] [Server thread/INFO]: [Citizens] Unable to use economy handling. Has Vault been enabled?
[20:35:54] [Server thread/INFO]: [WorldEdit] Enabling WorldEdit v6.1.3;7a097ca
[20:35:55] [Server thread/INFO]: WEPIF: Updated config file
[20:35:55] [Server thread/INFO]: WEPIF: bPermissions detected! Using bPermissions for permissions
[20:35:55] [Server thread/WARN]: [WorldEdit]

** This WorldEdit version does not fully support your version of Bukkit.
**
** When working with blocks or undoing, chests will be empty, signs
** will be blank, and so on. There will be no support for entity
** and biome-related functions.
**
** Please see http://wiki.sk89q.com/wiki/WorldEdit/Bukkit_adapters

[20:35:55] [Server thread/INFO]: [FS-SpigotCore] Enabling FS-SpigotCore v0.1
[20:35:55] [Server thread/INFO]: [FS-Hub] Enabling FS-Hub v0.1
[20:35:55] [Server thread/INFO]: Server permissions file permissions.yml is empty, ignoring it
[20:35:55] [Server thread/INFO]: Done (4.030s)! For help, type "help" or "?"
[20:35:55] [Server thread/INFO]: Day: 1
[20:35:55] [Craft Scheduler Thread - 0/INFO]: [TitleManager] Searching for updates.
[20:35:55] [Craft Scheduler Thread - 0/INFO]: [TitleManager] No update was found.
[20:35:55] [Server thread/INFO]: [Citizens] Loaded 25 NPCs.
[20:36:03] [User Authenticator #1/INFO]: UUID of player MinePile is 9b3af8fb-a4e1-40d0-ab48-4f84db2ea4dc
[20:36:03] [Server thread/INFO]: MinePile[/127.0.0.1:59177] logged in with entity id 218 at ([world]-7.5, 92.0, 2.5)
[20:36:04] [Server thread/ERROR]: Could not pass event PlayerGameModeChangeEvent to WorldEdit v6.1.3;7a097ca
org.bukkit.event.EventException
at org.bukkit.plugin.java.JavaPluginLoader$1.execute(JavaPluginLoader.java:306) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.RegisteredListener.callEvent(RegisteredListener.java:62) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.SimplePluginManager.fireEvent(SimplePluginManager.java:502) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.plugin.SimplePluginManager.callEvent(SimplePluginManager.java:487) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.craftbukkit.v1_11_R1.entity.CraftPlayer.setGameMode(CraftPlayer.java:788) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at com.forgestorm.hub.player.SetupHubPlayer.run(SetupHubPlayer.java:46) [forgestorm-hub-0.0.1-SNAPSHOT.jar:?]
at org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftTask.run(CraftTask.java:71) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at org.bukkit.craftbukkit.v1_11_R1.scheduler.CraftScheduler.mainThreadHeartbeat(CraftScheduler.java:353) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.D(MinecraftServer.java:730) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.DedicatedServer.D(DedicatedServer.java:399) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.C(MinecraftServer.java:675) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at net.minecraft.server.v1_11_R1.MinecraftServer.run(MinecraftServer.java:574) [hub-01.jar:git-Spigot-f950f8e-4d99fed]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_111]
Caused by: java.lang.UnsupportedOperationException: Not supported yet.
at de.bananaco.bpermissions.api.ApiLayer.hasPermission(ApiLayer.java:41) ~[?:?]
at com.sk89q.wepif.bPermissionsResolver.hasPermission(bPermissionsResolver.java:63) ~[?:?]
at com.sk89q.wepif.bPermissionsResolver.hasPermission(bPermissionsResolver.java:88) ~[?:?]
at com.sk89q.wepif.PermissionsResolverManager.hasPermission(PermissionsResolverManager.java:173) ~[?:?]
at com.sk89q.worldedit.bukkit.BukkitPlayer.hasPermission(BukkitPlayer.java:144) ~[?:?]
at com.sk89q.worldedit.session.SessionManager.get(SessionManager.java:168) ~[?:?]
at com.sk89q.worldedit.WorldEdit.getSession(WorldEdit.java:210) ~[?:?]
at com.sk89q.worldedit.bukkit.WorldEditListener.onGamemode(WorldEditListener.java:70) ~[?:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_111]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_111]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_111]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_111]
at org.bukkit.plugin.java.JavaPluginLoader$1.execute(JavaPluginLoader.java:302) ~[hub-01.jar:git-Spigot-f950f8e-4d99fed]
... 12 more
[20:36:34] [Server thread/INFO]: Stopping the server
[20:36:34] [Server thread/INFO]: Stopping server
[20:36:34] [Server thread/INFO]: [FS-Hub] Disabling FS-Hub v0.1
[20:36:34] [Server thread/INFO]: [FS-SpigotCore] Disabling FS-SpigotCore v0.1
[20:36:34] [Server thread/INFO]: [WorldEdit] Disabling WorldEdit v6.1.3;7a097ca
[20:36:34] [Server thread/INFO]: [Citizens] Disabling Citizens v2.0.21-SNAPSHOT (build 1445)
[20:36:34] [Server thread/INFO]: [TitleManager] Disabling TitleManager v1.5.13
[20:36:34] [Server thread/INFO]: Saving players
[20:36:34] [Server thread/INFO]: MinePile lost connection: Server closed
[20:36:34] [Server thread/INFO]: MinePile left the game
[20:36:34] [Server thread/INFO]: Saving worlds
[20:36:34] [Server thread/INFO]: Saving chunks for level 'world'/Overworld
[20:36:34] [Server thread/INFO]: Saving chunks for level 'world_nether'/Nether
[20:36:34] [Server thread/INFO]: Saving chunks for level 'world_the_end'/The End

Comment by wizjany

yea that's kind of weird because this line

[20:35:55] [Server thread/INFO]: WEPIF: bPermissions detected! Using bPermissions for permissions```

suggests that https://github.com/sk89q/WorldEdit/blob/master/worldedit-bukkit/src/main/java/com/sk89q/wepif/bPermissionsResolver.java#L33 somehow didn't throw the exception, which seems to imply the bperms api is floating around somewhere.