gradle run not working

[PhoenixIra]

Issue description

While working on the push request for the nested group support, I encountered that everytime I tried running gradle run, the server started but my browser just shoped "loading scm-svn-plugin" or "loading scm-ldap-plugin".

However, gradle run works fine on the main project.

Moreover, it seems that gradle does not check for the java version. I am not sure, if this is necessary, however for the main project, gradle does not accept java 8, for this plugin, gradle does accept java 8.

Bug Report

Describe how to reproduce the bug

running gradle run (tested with java 8 and java 14), waiting until the end of time for the browser to load the page.

Describe your SCM-Manager setup

I tested this on the clean develop branch using openjdk 8 and openjdk 14

Which operating system, browser and versions do you use?

Linux Ubuntu 20.04.2 Opera 74

Log of the bug

collapsed Log

``` Starting a Gradle Daemon, 1 stopped Daemon could not be reused, use --status for details Task :compileJava Task :plugin-xml Task :processResources Task :classes Task :copy-plugins Task :prepare-home Task :write-server-config Task :nodeSetup UP-TO-DATE Task :yarnSetup UP-TO-DATE Task :yarn_install UP-TO-DATE yarn run v1.22.5 $ ui-scripts plugin-watch [INFO] start scm-server at port 8081 [INFO] set stage DEVELOPMENT 2021-02-12 12:37:15.244 [main] [ ] INFO sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new simple ClassLoaderLifeCycle 2021-02-12 12:37:15.248 [main] [ ] INFO sonia.scm.lifecycle.BootstrapContextFilter - register for restart events 2021-02-12 12:37:15.249 [main] [ ] INFO sonia.scm.event.LegmanScmEventBus - create new event bus ScmEventBus-1 2021-02-12 12:37:15.285 [main] [ ] INFO sonia.scm.event.ScmEventBus - use sonia.scm.event.LegmanScmEventBus as event bus implementation 2021-02-12 12:37:15.333 [main] [ ] INFO sonia.scm.lifecycle.BootstrapContextListener - start scm-manager initialization 2021-02-12 12:37:15.333 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.lifecycle.classloading.BootstrapClassLoader@51549490 2021-02-12 12:37:15.342 [main] [ ] DEBUG sonia.scm.lifecycle.Versions - read previous version 2.0.0 from file Hash: f612beba14a0317b6321 Version: webpack 4.43.0 Time: 996ms Built at: 02/12/2021 12:37:15 PM Asset Size Chunks  Chunk Names scm-ldap-plugin.bundle.js 48.8 KiB scm-ldap-plugin [emitted] scm-ldap-plugin scm-ldap-plugin.bundle.js.map 39.4 KiB scm-ldap-plugin [emitted] [dev] scm-ldap-plugin Entrypoint scm-ldap-plugin = scm-ldap-plugin.bundle.js scm-ldap-plugin.bundle.js.map [0] multi ./node_modules/@scm-manager/ui-scripts/src/webpack-public-path.js ./src/main/js/index.ts 40 bytes {scm-ldap-plugin} [built] [./node_modules/@scm-manager/ui-scripts/src/webpack-public-path.js] 1.41 KiB {scm-ldap-plugin} [built] [./src/main/js/LdapConfiguration.tsx] 5.18 KiB {scm-ldap-plugin} [built] [./src/main/js/LdapConfigurationForm.tsx] 11.5 KiB {scm-ldap-plugin} [built] [./src/main/js/TestConnectionDialog.tsx] 11.1 KiB {scm-ldap-plugin} [built] [./src/main/js/index.ts] 1.35 KiB {scm-ldap-plugin} [built] [./src/main/js/profiles.ts] 3.65 KiB {scm-ldap-plugin} [built] [@scm-manager/ui-components] external "@scm-manager/ui-components" 42 bytes {scm-ldap-plugin} [built] [react] external "react" 42 bytes {scm-ldap-plugin} [built] [react-i18next] external "react-i18next" 42 bytes {scm-ldap-plugin} [built] 2021-02-12 12:37:15.551 [main] [ ] WARN sonia.scm.lifecycle.PluginBootstrap - install plugin scm-hg-plugin:2.0.0 2021-02-12 12:37:15.552 [main] [ ] DEBUG sonia.scm.plugin.PluginsInternal - extract plugin scm-hg-plugin 2021-02-12 12:37:15.612 [main] [ ] WARN sonia.scm.lifecycle.PluginBootstrap - install plugin scm-svn-plugin:2.0.0 2021-02-12 12:37:15.612 [main] [ ] DEBUG sonia.scm.plugin.PluginsInternal - extract plugin scm-svn-plugin 2021-02-12 12:37:15.753 [main] [ ] WARN sonia.scm.lifecycle.PluginBootstrap - install plugin scm-git-plugin:2.0.0 2021-02-12 12:37:15.753 [main] [ ] DEBUG sonia.scm.plugin.PluginsInternal - extract plugin scm-git-plugin 2021-02-12 12:37:15.864 [main] [ ] WARN sonia.scm.lifecycle.PluginBootstrap - install plugin scm-legacy-plugin:2.0.0 2021-02-12 12:37:15.864 [main] [ ] DEBUG sonia.scm.plugin.PluginsInternal - extract plugin scm-legacy-plugin 2021-02-12 12:37:15.875 [main] [ ] INFO sonia.scm.plugin.PluginProcessor - collect plugins 2021-02-12 12:37:15.876 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - extract 0 archives 2021-02-12 12:37:15.876 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - extract archives 2021-02-12 12:37:15.879 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - process 5 directories: [/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin, /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin, /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin, /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin, /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin] 2021-02-12 12:37:15.881 [main] [ ] TRACE sonia.scm.plugin.PluginProcessor - start building plugin tree 2021-02-12 12:37:15.885 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-hg-plugin and dependencies [] 2021-02-12 12:37:15.885 [main] [ ] TRACE sonia.scm.plugin.PluginTree - plugin: scm-hg-plugin 2021-02-12 12:37:15.885 [main] [ ] TRACE sonia.scm.plugin.PluginTree - dependencies: [] 2021-02-12 12:37:15.885 [main] [ ] TRACE sonia.scm.plugin.PluginTree - optional dependencies: [] 2021-02-12 12:37:15.889 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-git-plugin and dependencies [] 2021-02-12 12:37:15.889 [main] [ ] TRACE sonia.scm.plugin.PluginTree - plugin: scm-git-plugin 2021-02-12 12:37:15.889 [main] [ ] TRACE sonia.scm.plugin.PluginTree - dependencies: [] 2021-02-12 12:37:15.889 [main] [ ] TRACE sonia.scm.plugin.PluginTree - optional dependencies: [] 2021-02-12 12:37:15.890 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-svn-plugin and dependencies [] 2021-02-12 12:37:15.891 [main] [ ] TRACE sonia.scm.plugin.PluginTree - plugin: scm-svn-plugin 2021-02-12 12:37:15.891 [main] [ ] TRACE sonia.scm.plugin.PluginTree - dependencies: [] 2021-02-12 12:37:15.891 [main] [ ] TRACE sonia.scm.plugin.PluginTree - optional dependencies: [] 2021-02-12 12:37:15.892 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-legacy-plugin and dependencies [] 2021-02-12 12:37:15.892 [main] [ ] TRACE sonia.scm.plugin.PluginTree - plugin: scm-legacy-plugin 2021-02-12 12:37:15.892 [main] [ ] TRACE sonia.scm.plugin.PluginTree - dependencies: [] 2021-02-12 12:37:15.892 [main] [ ] TRACE sonia.scm.plugin.PluginTree - optional dependencies: [] 2021-02-12 12:37:15.893 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-ldap-plugin and dependencies [] 2021-02-12 12:37:15.894 [main] [ ] TRACE sonia.scm.plugin.PluginTree - plugin: scm-ldap-plugin 2021-02-12 12:37:15.894 [main] [ ] TRACE sonia.scm.plugin.PluginTree - dependencies: [] 2021-02-12 12:37:15.894 [main] [ ] TRACE sonia.scm.plugin.PluginTree - optional dependencies: [] 2021-02-12 12:37:15.898 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-hg-plugin and dependencies [] 2021-02-12 12:37:15.900 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-git-plugin and dependencies [] 2021-02-12 12:37:15.903 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-svn-plugin and dependencies [] 2021-02-12 12:37:15.905 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-legacy-plugin and dependencies [] 2021-02-12 12:37:15.907 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-ldap-plugin and dependencies [] 2021-02-12 12:37:15.910 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-hg-plugin and dependencies [] 2021-02-12 12:37:15.913 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-git-plugin and dependencies [] 2021-02-12 12:37:15.915 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-svn-plugin and dependencies [] 2021-02-12 12:37:15.916 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-legacy-plugin and dependencies [] 2021-02-12 12:37:15.917 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-ldap-plugin and dependencies [] 2021-02-12 12:37:15.919 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-hg-plugin and dependencies [] 2021-02-12 12:37:15.921 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-git-plugin and dependencies [] 2021-02-12 12:37:15.923 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-svn-plugin and dependencies [] 2021-02-12 12:37:15.924 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-legacy-plugin and dependencies [] 2021-02-12 12:37:15.925 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-ldap-plugin and dependencies [] 2021-02-12 12:37:15.929 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-hg-plugin and dependencies [] 2021-02-12 12:37:15.932 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-git-plugin and dependencies [] 2021-02-12 12:37:15.933 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-svn-plugin and dependencies [] 2021-02-12 12:37:15.934 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-legacy-plugin and dependencies [] 2021-02-12 12:37:15.935 [main] [ ] TRACE sonia.scm.plugin.ExplodedSmp - create exploded scm for plugin scm-ldap-plugin and dependencies [] 2021-02-12 12:37:15.935 [main] [ ] INFO sonia.scm.plugin.PluginProcessor - install plugin tree: +- scm-hg-plugin +- scm-git-plugin +- scm-svn-plugin +- scm-legacy-plugin +- scm-ldap-plugin 2021-02-12 12:37:15.936 [main] [ ] TRACE sonia.scm.plugin.PluginProcessor - create plugin wrappers and build classloaders 2021-02-12 12:37:15.937 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create parent fist classloader for plugin scm-ldap-plugin 2021-02-12 12:37:15.937 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new PluginClassLoader for scm-ldap-plugin 2021-02-12 12:37:15.937 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.plugin.DefaultPluginClassLoader for plugin scm-ldap-plugin 2021-02-12 12:37:15.939 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create WebResourceLoader for path /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp 2021-02-12 12:37:15.940 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create parent fist classloader for plugin scm-legacy-plugin 2021-02-12 12:37:15.940 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new PluginClassLoader for scm-legacy-plugin 2021-02-12 12:37:15.940 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.plugin.DefaultPluginClassLoader for plugin scm-legacy-plugin 2021-02-12 12:37:15.941 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create WebResourceLoader for path /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin/webapp 2021-02-12 12:37:15.942 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create parent fist classloader for plugin scm-svn-plugin 2021-02-12 12:37:15.942 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new PluginClassLoader for scm-svn-plugin 2021-02-12 12:37:15.942 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.plugin.DefaultPluginClassLoader for plugin scm-svn-plugin 2021-02-12 12:37:15.945 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create WebResourceLoader for path /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp 2021-02-12 12:37:15.947 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create parent fist classloader for plugin scm-git-plugin 2021-02-12 12:37:15.947 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new PluginClassLoader for scm-git-plugin 2021-02-12 12:37:15.947 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.plugin.DefaultPluginClassLoader for plugin scm-git-plugin 2021-02-12 12:37:15.956 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create WebResourceLoader for path /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp 2021-02-12 12:37:15.956 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create parent fist classloader for plugin scm-hg-plugin 2021-02-12 12:37:15.957 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.ClassLoaderLifeCycle - create new PluginClassLoader for scm-hg-plugin 2021-02-12 12:37:15.957 [main] [ ] DEBUG sonia.scm.lifecycle.classloading.SimpleClassLoaderLifeCycle - init classloader sonia.scm.plugin.DefaultPluginClassLoader for plugin scm-hg-plugin 2021-02-12 12:37:15.959 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - create WebResourceLoader for path /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp 2021-02-12 12:37:15.959 [main] [ ] DEBUG sonia.scm.plugin.PluginProcessor - collected 5 plugins 2021-02-12 12:37:16.115 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.io.FileSystem, using default implementation class sonia.scm.io.DefaultFileSystem 2021-02-12 12:37:16.115 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.io.FileSystem to class sonia.scm.io.DefaultFileSystem 2021-02-12 12:37:16.116 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.lifecycle.Restarter, using default implementation class sonia.scm.lifecycle.DefaultRestarter 2021-02-12 12:37:16.116 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.lifecycle.Restarter to class sonia.scm.lifecycle.DefaultRestarter 2021-02-12 12:37:16.147 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.ConfigurationStoreFactory, using default implementation class sonia.scm.store.JAXBConfigurationStoreFactory 2021-02-12 12:37:16.147 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.ConfigurationStoreFactory to class sonia.scm.store.JAXBConfigurationStoreFactory 2021-02-12 12:37:16.148 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.ConfigurationEntryStoreFactory, using default implementation class sonia.scm.store.JAXBConfigurationEntryStoreFactory 2021-02-12 12:37:16.148 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.ConfigurationEntryStoreFactory to class sonia.scm.store.JAXBConfigurationEntryStoreFactory 2021-02-12 12:37:16.148 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.DataStoreFactory, using default implementation class sonia.scm.store.JAXBDataStoreFactory 2021-02-12 12:37:16.148 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.DataStoreFactory to class sonia.scm.store.JAXBDataStoreFactory 2021-02-12 12:37:16.149 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.BlobStoreFactory, using default implementation class sonia.scm.store.FileBlobStoreFactory 2021-02-12 12:37:16.149 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.BlobStoreFactory to class sonia.scm.store.FileBlobStoreFactory 2021-02-12 12:37:16.149 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.V1PropertyDAO, using default implementation class sonia.scm.update.xml.XmlV1PropertyDAO 2021-02-12 12:37:16.149 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.V1PropertyDAO to class sonia.scm.update.xml.XmlV1PropertyDAO 2021-02-12 12:37:16.150 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.PropertyFileAccess, using default implementation class sonia.scm.store.JAXBPropertyFileAccess 2021-02-12 12:37:16.150 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.PropertyFileAccess to class sonia.scm.store.JAXBPropertyFileAccess 2021-02-12 12:37:16.150 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.BlobDirectoryAccess, using default implementation class sonia.scm.store.DefaultBlobDirectoryAccess 2021-02-12 12:37:16.150 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.BlobDirectoryAccess to class sonia.scm.store.DefaultBlobDirectoryAccess 2021-02-12 12:37:16.237 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.repository.RepositoryMigrationPlan and name: migration-plan.xml 2021-02-12 12:37:16.237 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.update.repository.RepositoryMigrationPlan from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/migration-plan.xml 2021-02-12 12:37:16.240 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.V1Properties and name: repository-properties-v1.xml 2021-02-12 12:37:16.242 [main] [ ] INFO sonia.scm.update.repository.XmlRepositoryV1UpdateStep - no v1 repositories database file found 2021-02-12 12:37:16.280 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.V1Properties and name: repository-properties-v1.xml 2021-02-12 12:37:16.286 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.group.xml.XmlGroupDatabase and name: groups.xml 2021-02-12 12:37:16.286 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.group.xml.XmlGroupDatabase from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/groups.xml 2021-02-12 12:37:16.287 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.V1Properties and name: group-properties-v1.xml 2021-02-12 12:37:16.293 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.user.xml.XmlUserDatabase and name: users.xml 2021-02-12 12:37:16.293 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.user.xml.XmlUserDatabase from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/users.xml 2021-02-12 12:37:16.296 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.V1Properties and name: user-properties-v1.xml 2021-02-12 12:37:16.307 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:16.308 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:16.308 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@7e97551f, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@400d912a, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@9f6e406] 2021-02-12 12:37:16.309 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:16.311 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:16.311 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@4a31c2ee, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@d400943, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@22101c80] 2021-02-12 12:37:16.311 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:16.312 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [svn] 2021-02-12 12:37:16.312 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:16.312 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:16.313 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [git] 2021-02-12 12:37:16.313 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:16.313 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:16.314 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [hg] 2021-02-12 12:37:16.314 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:16.324 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sorting available update steps: 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.repository.xml for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.group.xml for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.user.xml for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.plugin.git for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.git.lfs for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.plugin.svn for version 2.0.0 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.repository.xml for version 2.0.1 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.security.xml for version 2.0.1 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.repository.xml for version 2.0.2 2021-02-12 12:37:16.327 [main] [ ] TRACE sonia.scm.update.UpdateEngine - sonia.scm.repository.xml for version 2.0.3 2021-02-12 12:37:16.328 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.UpdateVersionInfo and name: executedUpdates.xml 2021-02-12 12:37:16.329 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationEntryStore - load configuration from /home/phoenix/scm-ldap-plugin/build/scm-home/config/executedUpdates.xml 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@1a411233 to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@70325d20 to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@7c2327fa to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@4d847d32 to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@5f462e3b to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@3d7fa3ae to configuration entry store 2021-02-12 12:37:16.343 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.update.UpdateVersionInfo@58065f0c to configuration entry store 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.repository.xml and version 2.0.0 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.repository.xml from configuration store 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.repository.xml: 2.0.3; step will be executed: false 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.group.xml and version 2.0.0 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.group.xml from configuration store 2021-02-12 12:37:16.344 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.group.xml: 2.0.0; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.user.xml and version 2.0.0 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.user.xml from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.user.xml: 2.0.0; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.plugin.git and version 2.0.0 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.plugin.git from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.plugin.git: 2.0.0; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.git.lfs and version 2.0.0 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.git.lfs from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.git.lfs: 2.0.0; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.plugin.svn and version 2.0.0 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.plugin.svn from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.plugin.svn: 2.0.0; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.repository.xml and version 2.0.1 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.repository.xml from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.repository.xml: 2.0.3; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.security.xml and version 2.0.1 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.security.xml from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.security.xml: 2.0.1; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.repository.xml and version 2.0.2 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.repository.xml from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.repository.xml: 2.0.3; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - checking whether to run update step for type sonia.scm.repository.xml and version 2.0.3 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item sonia.scm.repository.xml from configuration store 2021-02-12 12:37:16.345 [main] [ ] TRACE sonia.scm.update.UpdateEngine - latest version for type sonia.scm.repository.xml: 2.0.3; step will be executed: false 2021-02-12 12:37:16.345 [main] [ ] DEBUG sonia.scm.lifecycle.Versions - write new version 2.0.0 to file 2021-02-12 12:37:16.362 [main] [ ] INFO sonia.scm.lifecycle.modules.ApplicationModuleProvider - add module class sonia.scm.legacy.LegacyModule 2021-02-12 12:37:16.363 [main] [ ] INFO sonia.scm.lifecycle.modules.ApplicationModuleProvider - add module class sonia.scm.web.GitServletModule 2021-02-12 12:37:16.363 [main] [ ] INFO sonia.scm.lifecycle.modules.ApplicationModuleProvider - add module class sonia.scm.web.HgServletModule 2021-02-12 12:37:16.363 [main] [ ] INFO sonia.scm.lifecycle.modules.ApplicationModuleProvider - add module class sonia.scm.web.SvnServletModule 2021-02-12 12:37:16.363 [main] [ ] INFO sonia.scm.lifecycle.modules.ApplicationModuleProvider - add module class sonia.scm.auth.ldap.resource.LdapModule 2021-02-12 12:37:16.367 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.io.FileSystem, using default implementation class sonia.scm.io.DefaultFileSystem 2021-02-12 12:37:16.367 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.io.FileSystem to class sonia.scm.io.DefaultFileSystem 2021-02-12 12:37:16.367 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.lifecycle.Restarter, using default implementation class sonia.scm.lifecycle.DefaultRestarter 2021-02-12 12:37:16.367 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.lifecycle.Restarter to class sonia.scm.lifecycle.DefaultRestarter 2021-02-12 12:37:16.367 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.ConfigurationStoreFactory, using default implementation class sonia.scm.store.JAXBConfigurationStoreFactory 2021-02-12 12:37:16.367 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.ConfigurationStoreFactory to class sonia.scm.store.JAXBConfigurationStoreFactory 2021-02-12 12:37:16.367 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.ConfigurationEntryStoreFactory, using default implementation class sonia.scm.store.JAXBConfigurationEntryStoreFactory 2021-02-12 12:37:16.367 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.ConfigurationEntryStoreFactory to class sonia.scm.store.JAXBConfigurationEntryStoreFactory 2021-02-12 12:37:16.367 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.DataStoreFactory, using default implementation class sonia.scm.store.JAXBDataStoreFactory 2021-02-12 12:37:16.368 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.DataStoreFactory to class sonia.scm.store.JAXBDataStoreFactory 2021-02-12 12:37:16.368 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.store.BlobStoreFactory, using default implementation class sonia.scm.store.FileBlobStoreFactory 2021-02-12 12:37:16.368 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.store.BlobStoreFactory to class sonia.scm.store.FileBlobStoreFactory 2021-02-12 12:37:16.368 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.V1PropertyDAO, using default implementation class sonia.scm.update.xml.XmlV1PropertyDAO 2021-02-12 12:37:16.368 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.V1PropertyDAO to class sonia.scm.update.xml.XmlV1PropertyDAO 2021-02-12 12:37:16.368 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.PropertyFileAccess, using default implementation class sonia.scm.store.JAXBPropertyFileAccess 2021-02-12 12:37:16.368 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.PropertyFileAccess to class sonia.scm.store.JAXBPropertyFileAccess 2021-02-12 12:37:16.368 [main] [ ] TRACE sonia.scm.lifecycle.modules.BootstrapModule - no override available for interface sonia.scm.update.BlobDirectoryAccess, using default implementation class sonia.scm.store.DefaultBlobDirectoryAccess 2021-02-12 12:37:16.368 [main] [ ] DEBUG sonia.scm.lifecycle.modules.BootstrapModule - bind interface sonia.scm.update.BlobDirectoryAccess to class sonia.scm.store.DefaultBlobDirectoryAccess 2021-02-12 12:37:16.376 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.filter.BaseUrlFilter to filter chain 2021-02-12 12:37:16.376 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.filter.BaseUrlFilter to /* and [] 2021-02-12 12:37:16.376 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.legacy.LegacyProtocolServletAuthenticationFilter to filter chain 2021-02-12 12:37:16.377 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.legacy.LegacyProtocolServletAuthenticationFilter to /git/* and [/hg/*, /svn/*] 2021-02-12 12:37:16.377 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.web.filter.DefaultHttpProtocolServletAuthenticationFilter to filter chain 2021-02-12 12:37:16.377 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.web.filter.DefaultHttpProtocolServletAuthenticationFilter to /repo/* and [] 2021-02-12 12:37:16.377 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.web.security.ApiAuthenticationFilter to filter chain 2021-02-12 12:37:16.377 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.web.security.ApiAuthenticationFilter to /api/* and [/debug.html] 2021-02-12 12:37:16.377 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.filter.MDCFilter to filter chain 2021-02-12 12:37:16.378 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.filter.MDCFilter to /* and [] 2021-02-12 12:37:16.378 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind filter class sonia.scm.web.security.TokenRefreshFilter to filter chain 2021-02-12 12:37:16.378 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob filter class sonia.scm.web.security.TokenRefreshFilter to /api/* and [/debug.html] 2021-02-12 12:37:16.378 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind servlet class sonia.scm.lifecycle.RestartServlet to servlet chain 2021-02-12 12:37:16.378 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob servlet class sonia.scm.lifecycle.RestartServlet to /restart and [] 2021-02-12 12:37:16.378 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind servlet class sonia.scm.web.i18n.I18nServlet to servlet chain 2021-02-12 12:37:16.378 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind regex servlet class sonia.scm.web.i18n.I18nServlet to /locales/[a-z\-A-Z]*/plugins.json and [] 2021-02-12 12:37:16.379 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind servlet class sonia.scm.web.protocol.HttpProtocolServlet to servlet chain 2021-02-12 12:37:16.379 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind glob servlet class sonia.scm.web.protocol.HttpProtocolServlet to /repo/* and [] 2021-02-12 12:37:16.379 [main] [ ] INFO sonia.scm.filter.WebElementModule - bind servlet class sonia.scm.WebResourceServlet to servlet chain 2021-02-12 12:37:16.380 [main] [ ] DEBUG sonia.scm.filter.WebElementModule - bind regex servlet class sonia.scm.WebResourceServlet to /(?!api/|git/|hg/|svn/|hook/|repo/).* and [] 2021-02-12 12:37:16.385 [main] [ ] DEBUG sonia.scm.util.ScmConfigurationUtil - could not find ScmConfiguration file at /home/phoenix/scm-ldap-plugin/build/scm-home/config/config.xml 2021-02-12 12:37:16.401 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.plugin.PluginManager, using default implementation class sonia.scm.plugin.DefaultPluginManager 2021-02-12 12:37:16.401 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.plugin.PluginManager to class sonia.scm.plugin.DefaultPluginManager 2021-02-12 12:37:16.402 [main] [ ] INFO sonia.scm.plugin.DefaultExtensionProcessor - start processing extensions 2021-02-12 12:37:16.406 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind extensions to extension points 2021-02-12 12:37:16.406 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.RepositoryManagerDecoratorFactory 2021-02-12 12:37:16.407 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.group.GroupManagerDecoratorFactory 2021-02-12 12:37:16.408 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.BlameLinePreProcessorFactory 2021-02-12 12:37:16.409 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.JwtAccessTokenBuilderFactory to sonia.scm.security.AccessTokenBuilderFactory 2021-02-12 12:37:16.409 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.security.AccessTokenValidator 2021-02-12 12:37:16.410 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.XsrfAccessTokenValidator to multibinder of sonia.scm.security.AccessTokenValidator 2021-02-12 12:37:16.410 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.security.AccessTokenEnricher 2021-02-12 12:37:16.411 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.XsrfAccessTokenEnricher to multibinder of sonia.scm.security.AccessTokenEnricher 2021-02-12 12:37:16.411 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind type of interface com.google.inject.Module is manual 2021-02-12 12:37:16.411 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.JwtAccessTokenResolver to sonia.scm.security.AccessTokenResolver 2021-02-12 12:37:16.411 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.RepositoryPathMatcher 2021-02-12 12:37:16.412 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.GitRepositoryPathMatcher to multibinder of sonia.scm.repository.RepositoryPathMatcher 2021-02-12 12:37:16.412 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.BlameLinePreProcessor 2021-02-12 12:37:16.412 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.spi.RepositoryServiceResolver 2021-02-12 12:37:16.413 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.spi.SvnRepositoryServiceResolver to multibinder of sonia.scm.repository.spi.RepositoryServiceResolver 2021-02-12 12:37:16.414 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.spi.GitRepositoryServiceResolver to multibinder of sonia.scm.repository.spi.RepositoryServiceResolver 2021-02-12 12:37:16.414 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.spi.HgRepositoryServiceResolver to multibinder of sonia.scm.repository.spi.RepositoryServiceResolver 2021-02-12 12:37:16.414 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.RepositoryContentInitializer 2021-02-12 12:37:16.415 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.ReadmeRepositoryContentInitializer to multibinder of sonia.scm.repository.RepositoryContentInitializer 2021-02-12 12:37:16.415 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.ModificationsPreProcessorFactory 2021-02-12 12:37:16.415 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.user.UserManagerDecoratorFactory 2021-02-12 12:37:16.416 [main] [ ] WARN sonia.scm.plugin.ExtensionBinder - could not find extension for extension point interface sonia.scm.api.v2.resources.LogoutRedirection 2021-02-12 12:37:16.416 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.ModificationsPreProcessor 2021-02-12 12:37:16.416 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.repository.XmlRepositoryV1UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.group.XmlGroupV1UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.repository.XmlRepositoryFileNameUpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.user.XmlUserV1UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.update.GitV2UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.417 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.repository.PublicFlagUpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.418 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.lfs.LfsV1UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.418 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.security.XmlSecurityV1UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.418 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.update.repository.MigrateVerbsToPermissionRoles to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.418 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.SvnV2UpdateStep to multibinder of sonia.scm.migration.UpdateStep 2021-02-12 12:37:16.426 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.protocolcommand.CommandInterpreterFactory 2021-02-12 12:37:16.427 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.protocolcommand.git.GitCommandInterpreterFactory to multibinder of sonia.scm.protocolcommand.CommandInterpreterFactory 2021-02-12 12:37:16.427 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.lfs.LFSAuthCommand to multibinder of sonia.scm.protocolcommand.CommandInterpreterFactory 2021-02-12 12:37:16.427 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for javax.servlet.http.HttpSessionListener 2021-02-12 12:37:16.428 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.spi.ScmProviderHttpServletDecoratorFactory 2021-02-12 12:37:16.428 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.GitPermissionFilterFactory to multibinder of sonia.scm.repository.spi.ScmProviderHttpServletDecoratorFactory 2021-02-12 12:37:16.429 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.HgPermissionFilterFactory to multibinder of sonia.scm.repository.spi.ScmProviderHttpServletDecoratorFactory 2021-02-12 12:37:16.429 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.SvnGZipFilterFactory to multibinder of sonia.scm.repository.spi.ScmProviderHttpServletDecoratorFactory 2021-02-12 12:37:16.429 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.SvnPermissionFilterFactory to multibinder of sonia.scm.repository.spi.ScmProviderHttpServletDecoratorFactory 2021-02-12 12:37:16.429 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.NamespaceStrategy 2021-02-12 12:37:16.430 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.CurrentYearNamespaceStrategy to multibinder of sonia.scm.repository.NamespaceStrategy 2021-02-12 12:37:16.430 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.RepositoryTypeNamespaceStrategy to multibinder of sonia.scm.repository.NamespaceStrategy 2021-02-12 12:37:16.430 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.UsernameNamespaceStrategy to multibinder of sonia.scm.repository.NamespaceStrategy 2021-02-12 12:37:16.430 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.CustomNamespaceStrategy to multibinder of sonia.scm.repository.NamespaceStrategy 2021-02-12 12:37:16.430 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.api.v2.resources.HalEnricher 2021-02-12 12:37:16.432 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.legacy.LegacyIndexHalEnricher to multibinder of sonia.scm.api.v2.resources.HalEnricher 2021-02-12 12:37:16.433 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.auth.ldap.resource.IndexLinkEnricher to multibinder of sonia.scm.api.v2.resources.HalEnricher 2021-02-12 12:37:16.433 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.ChangesetPreProcessor 2021-02-12 12:37:16.434 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind type of interface org.apache.shiro.realm.Realm is manual 2021-02-12 12:37:16.434 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.web.WebTokenGenerator 2021-02-12 12:37:16.434 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.BasicWebTokenGenerator to multibinder of sonia.scm.web.WebTokenGenerator 2021-02-12 12:37:16.435 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.BearerWebTokenGenerator to multibinder of sonia.scm.web.WebTokenGenerator 2021-02-12 12:37:16.435 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.CookieBearerWebTokenGenerator to multibinder of sonia.scm.web.WebTokenGenerator 2021-02-12 12:37:16.435 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.RepositoryHandler 2021-02-12 12:37:16.435 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.HgRepositoryHandler to multibinder of sonia.scm.repository.RepositoryHandler 2021-02-12 12:37:16.435 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.SvnRepositoryHandler to multibinder of sonia.scm.repository.RepositoryHandler 2021-02-12 12:37:16.436 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.GitRepositoryHandler to multibinder of sonia.scm.repository.RepositoryHandler 2021-02-12 12:37:16.436 [main] [ ] WARN sonia.scm.plugin.ExtensionBinder - could not find extension for extension point interface sonia.scm.io.FileSystem 2021-02-12 12:37:16.436 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.DefaultJwtAccessTokenRefreshStrategy to sonia.scm.security.JwtAccessTokenRefreshStrategy 2021-02-12 12:37:16.436 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.security.AuthorizationCollector 2021-02-12 12:37:16.436 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.security.DefaultAuthorizationCollector to multibinder of sonia.scm.security.AuthorizationCollector 2021-02-12 12:37:16.437 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.FileObjectPreProcessor 2021-02-12 12:37:16.437 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.net.ahc.ContentTransformer 2021-02-12 12:37:16.438 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.net.ahc.JsonContentTransformer to multibinder of sonia.scm.net.ahc.ContentTransformer 2021-02-12 12:37:16.438 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.net.ahc.XmlContentTransformer to multibinder of sonia.scm.net.ahc.ContentTransformer 2021-02-12 12:37:16.438 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.RepositoryRequestListener 2021-02-12 12:37:16.438 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for javax.servlet.ServletContextListener 2021-02-12 12:37:16.439 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.GitWorkQueueShutdownListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.439 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.api.v2.resources.LinkEnricherAutoRegistration to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.439 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.spi.GitLfsFilterContextListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.439 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.spi.GitConfigContextListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.439 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.lifecycle.SetupContextListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.440 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.GitContextListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.440 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.HealthCheckContextListener to multibinder of javax.servlet.ServletContextListener 2021-02-12 12:37:16.440 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.web.UserAgentProvider 2021-02-12 12:37:16.441 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.BrowserUserAgentProvider to multibinder of sonia.scm.web.UserAgentProvider 2021-02-12 12:37:16.441 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.SvnUserAgentProvider to multibinder of sonia.scm.web.UserAgentProvider 2021-02-12 12:37:16.441 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.GitUserAgentProvider to multibinder of sonia.scm.web.UserAgentProvider 2021-02-12 12:37:16.441 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.HgUserAgentProvider to multibinder of sonia.scm.web.UserAgentProvider 2021-02-12 12:37:16.441 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.FileObjectPreProcessorFactory 2021-02-12 12:37:16.442 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.api.ScmProtocolProvider 2021-02-12 12:37:16.442 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.GitScmProtocolProviderWrapper to multibinder of sonia.scm.repository.api.ScmProtocolProvider 2021-02-12 12:37:16.442 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.SvnScmProtocolProviderWrapper to multibinder of sonia.scm.repository.api.ScmProtocolProvider 2021-02-12 12:37:16.443 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.web.HgScmProtocolProviderWrapper to multibinder of sonia.scm.repository.api.ScmProtocolProvider 2021-02-12 12:37:16.443 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.group.GroupResolver 2021-02-12 12:37:16.443 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.auth.ldap.LdapGroupResolver to multibinder of sonia.scm.group.GroupResolver 2021-02-12 12:37:16.443 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.ChangesetPreProcessorFactory 2021-02-12 12:37:16.444 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.repository.HealthCheck 2021-02-12 12:37:16.444 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.DBFormatHealthCheck to multibinder of sonia.scm.repository.HealthCheck 2021-02-12 12:37:16.445 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.GitHealthCheck to multibinder of sonia.scm.repository.HealthCheck 2021-02-12 12:37:16.445 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.repository.HgHealthCheck to multibinder of sonia.scm.repository.HealthCheck 2021-02-12 12:37:16.445 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - create multibinder for sonia.scm.web.JsonEnricher 2021-02-12 12:37:16.445 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.api.v2.resources.HgConfigInIndexResource to multibinder of sonia.scm.web.JsonEnricher 2021-02-12 12:37:16.445 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.api.v2.resources.SvnConfigInIndexResource to multibinder of sonia.scm.web.JsonEnricher 2021-02-12 12:37:16.446 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.api.v2.resources.GitConfigInIndexResource to multibinder of sonia.scm.web.JsonEnricher 2021-02-12 12:37:16.446 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind sonia.scm.api.v2.resources.GitRepositoryConfigEnricher to multibinder of sonia.scm.web.JsonEnricher 2021-02-12 12:37:16.446 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extensions 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.repository.GitRepositoryModifyListener as eager singleton 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.protocolcommand.git.GitCommandProtocol 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.api.v2.resources.GitRepositoryConfigChangeClearRepositoryCacheListener as eager singleton 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.repository.RemoveDeletedRepositoryRole as eager singleton 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.security.SyncingRealmHelper 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.repository.LastModifiedUpdateListener as eager singleton 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.user.AnonymousUserDeletionEventHandler as eager singleton 2021-02-12 12:37:16.447 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.api.v2.resources.HalEnricherRegistry 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind loose extension class sonia.scm.config.ScmConfigurationChangedListener as eager singleton 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest providers 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.ConcurrentModificationExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.FallbackExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.InvalidFormatExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.AuthorizationExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.FieldContainerResponseFilter 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.JavaxValidationExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.UriInfoFilter 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.RestActionUploadResultMessageWriter 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.AlreadyExistsExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.ScmSecurityExceptionMapper 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.CacheControlResponseFilter 2021-02-12 12:37:16.448 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.AuthenticationExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.NotSupportedExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.DisabledAccountExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.security.SecurityRequestFilter 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.NotAuthorizedExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.ContextualFallbackExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.ScmConstraintValidationExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.TemplateEngineViewable 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.filter.GZipResponseFilter 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.JsonMarshallingResponseFilter 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.NotFoundExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.JSONContextResolver 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.JaxNotFoundExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.v2.resources.InternalRepositoryExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.NotAllowedExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.JsonParseExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.BadRequestExceptionMapper 2021-02-12 12:37:16.449 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest provider class sonia.scm.api.rest.ExcessiveAttemptsExceptionMapper 2021-02-12 12:37:16.450 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resources 2021-02-12 12:37:16.451 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.RepositoryVerbResource 2021-02-12 12:37:16.452 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.auth.ldap.resource.LdapConfigResource 2021-02-12 12:37:16.452 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.legacy.LegacyRepositoryService 2021-02-12 12:37:16.452 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.GroupRootResource 2021-02-12 12:37:16.453 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.GitConfigResource 2021-02-12 12:37:16.454 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.AuthenticationResource 2021-02-12 12:37:16.454 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.debug.DebugResource 2021-02-12 12:37:16.454 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.SvnConfigResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.IndexResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.PluginRootResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.RepositoryRootResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.GlobalPermissionResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.NamespaceStrategyResource 2021-02-12 12:37:16.455 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.RepositoryTypeRootResource 2021-02-12 12:37:16.456 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.ConfigResource 2021-02-12 12:37:16.456 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.MeResource 2021-02-12 12:37:16.456 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.UIRootResource 2021-02-12 12:37:16.456 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.AutoCompleteResource 2021-02-12 12:37:16.457 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.HgConfigResource 2021-02-12 12:37:16.457 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.RepositoryRoleRootResource 2021-02-12 12:37:16.457 [main] [ ] DEBUG sonia.scm.plugin.ExtensionBinder - bind rest resource class sonia.scm.api.v2.resources.UserRootResource 2021-02-12 12:37:16.457 [main] [ ] INFO sonia.scm.plugin.DefaultExtensionProcessor - bound extensions in 55.35 ms 2021-02-12 12:37:16.459 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.web.security.AdministrationContext, using default implementation class sonia.scm.web.security.DefaultAdministrationContext 2021-02-12 12:37:16.459 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.web.security.AdministrationContext to class sonia.scm.web.security.DefaultAdministrationContext 2021-02-12 12:37:16.459 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.cache.CacheManager, using default implementation class sonia.scm.cache.GuavaCacheManager 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.cache.CacheManager to class sonia.scm.cache.GuavaCacheManager 2021-02-12 12:37:16.460 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface org.apache.shiro.cache.CacheManager, using default implementation class sonia.scm.cache.GuavaCacheManager 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface org.apache.shiro.cache.CacheManager to class sonia.scm.cache.GuavaCacheManager 2021-02-12 12:37:16.460 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.group.GroupDAO, using default implementation class sonia.scm.group.xml.XmlGroupDAO 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.group.GroupDAO to class sonia.scm.group.xml.XmlGroupDAO 2021-02-12 12:37:16.460 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.user.UserDAO, using default implementation class sonia.scm.user.xml.XmlUserDAO 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.user.UserDAO to class sonia.scm.user.xml.XmlUserDAO 2021-02-12 12:37:16.460 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.repository.RepositoryDAO, using default implementation class sonia.scm.repository.xml.XmlRepositoryDAO 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.repository.RepositoryDAO to class sonia.scm.repository.xml.XmlRepositoryDAO 2021-02-12 12:37:16.460 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.repository.RepositoryRoleDAO, using default implementation class sonia.scm.repository.xml.XmlRepositoryRoleDAO 2021-02-12 12:37:16.460 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.repository.RepositoryRoleDAO to class sonia.scm.repository.xml.XmlRepositoryRoleDAO 2021-02-12 12:37:16.462 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.repository.RepositoryManager, using default implementation class sonia.scm.repository.DefaultRepositoryManager 2021-02-12 12:37:16.462 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind undecorated interface sonia.scm.repository.RepositoryManager to class sonia.scm.repository.DefaultRepositoryManager 2021-02-12 12:37:16.463 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.repository.RepositoryManager to provider class sonia.scm.repository.RepositoryManagerProvider 2021-02-12 12:37:16.464 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.user.UserManager, using default implementation class sonia.scm.user.DefaultUserManager 2021-02-12 12:37:16.464 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind undecorated interface sonia.scm.user.UserManager to class sonia.scm.user.DefaultUserManager 2021-02-12 12:37:16.464 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.user.UserManager to provider class sonia.scm.user.UserManagerProvider 2021-02-12 12:37:16.464 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.user.UserDisplayManager, using default implementation class sonia.scm.user.DefaultUserDisplayManager 2021-02-12 12:37:16.464 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.user.UserDisplayManager to class sonia.scm.user.DefaultUserDisplayManager 2021-02-12 12:37:16.465 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.group.GroupManager, using default implementation class sonia.scm.group.DefaultGroupManager 2021-02-12 12:37:16.465 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind undecorated interface sonia.scm.group.GroupManager to class sonia.scm.group.DefaultGroupManager 2021-02-12 12:37:16.465 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.group.GroupManager to provider class sonia.scm.group.GroupManagerProvider 2021-02-12 12:37:16.465 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.group.GroupDisplayManager, using default implementation class sonia.scm.group.DefaultGroupDisplayManager 2021-02-12 12:37:16.465 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.group.GroupDisplayManager to class sonia.scm.group.DefaultGroupDisplayManager 2021-02-12 12:37:16.466 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.group.GroupCollector, using default implementation class sonia.scm.group.DefaultGroupCollector 2021-02-12 12:37:16.466 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.group.GroupCollector to class sonia.scm.group.DefaultGroupCollector 2021-02-12 12:37:16.466 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmServletModule - no override available for interface sonia.scm.web.cgi.CGIExecutorFactory, using default implementation class sonia.scm.web.cgi.DefaultCGIExecutorFactory 2021-02-12 12:37:16.466 [main] [ ] DEBUG sonia.scm.lifecycle.modules.ScmServletModule - bind interface sonia.scm.web.cgi.CGIExecutorFactory to class sonia.scm.web.cgi.DefaultCGIExecutorFactory 2021-02-12 12:37:16.519 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.security.DefaultRealm 2021-02-12 12:37:16.519 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.web.security.AdministrationContextRealm 2021-02-12 12:37:16.519 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.security.AnonymousRealm 2021-02-12 12:37:16.519 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.auth.ldap.LdapRealm 2021-02-12 12:37:16.520 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.legacy.LegacyRealm 2021-02-12 12:37:16.520 [main] [ ] INFO sonia.scm.lifecycle.modules.ScmSecurityModule - bind security realm class sonia.scm.security.BearerRealm 2021-02-12 12:37:16.617 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.repository.GitRepositoryModifyListener 2021-02-12 12:37:16.624 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.api.v2.resources.GitRepositoryConfigChangeClearRepositoryCacheListener 2021-02-12 12:37:16.625 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.repository.RemoveDeletedRepositoryRole 2021-02-12 12:37:16.627 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.repository.LastModifiedUpdateListener 2021-02-12 12:37:16.627 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.user.AnonymousUserDeletionEventHandler 2021-02-12 12:37:16.629 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.config.ScmConfigurationChangedListener 2021-02-12 12:37:16.783 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.security.AuthorizationChangedEventProducer 2021-02-12 12:37:16.798 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.debug.DebugHook 2021-02-12 12:37:16.917 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.repository.DefaultRepositoryRoleManager 2021-02-12 12:37:16.923 [main] [ ] TRACE sonia.scm.lifecycle.modules.EagerSingletonModule - register eager singleton class sonia.scm.user.DefaultUserManager 2021-02-12 12:37:17.017 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheConfigurationReader - read cache configuration from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/config/gcache.xml 2021-02-12 12:37:17.019 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheConfigurationReader - no module configuration found 2021-02-12 12:37:17.019 [main] [ ] WARN sonia.scm.cache.GuavaCacheConfigurationReader - could not find manual configuration at /home/phoenix/scm-ldap-plugin/build/scm-home/ext/gcache.xml 2021-02-12 12:37:17.020 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.externalGroups from configured configuration GuavaNamedCacheConfiguration{expireAfterAccess=60, expireAfterWrite=120, maximumSize=1000} 2021-02-12 12:37:17.021 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.externalGroups from builder: CacheBuilder{maximumSize=1000, expireAfterWrite=120000000000ns, expireAfterAccess=60000000000ns} 2021-02-12 12:37:17.021 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.authorizing from configured configuration GuavaNamedCacheConfiguration{copyStrategy=READ, expireAfterAccess=1200, expireAfterWrite=2400, maximumSize=1000} 2021-02-12 12:37:17.021 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.authorizing from builder: CacheBuilder{maximumSize=1000, expireAfterWrite=2400000000000ns, expireAfterAccess=1200000000000ns} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.plugins from configured configuration GuavaNamedCacheConfiguration{expireAfterWrite=3600, maximumSize=5} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.plugins from builder: CacheBuilder{maximumSize=5, expireAfterWrite=3600000000000ns} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.search.users from configured configuration GuavaNamedCacheConfiguration{expireAfterWrite=5400, maximumSize=10000} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.search.users from builder: CacheBuilder{maximumSize=10000, expireAfterWrite=5400000000000ns} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.search.groups from configured configuration GuavaNamedCacheConfiguration{expireAfterWrite=5400, maximumSize=1000} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.search.groups from builder: CacheBuilder{maximumSize=1000, expireAfterWrite=5400000000000ns} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.cmd.log from configured configuration GuavaNamedCacheConfiguration{copyStrategy=READWRITE, maximumSize=500} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.cmd.log from builder: CacheBuilder{maximumSize=500} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.cmd.browse from configured configuration GuavaNamedCacheConfiguration{copyStrategy=READWRITE, maximumSize=3000} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.cmd.browse from builder: CacheBuilder{maximumSize=3000} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.cmd.blame from configured configuration GuavaNamedCacheConfiguration{copyStrategy=READWRITE, maximumSize=1000} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.cmd.blame from builder: CacheBuilder{maximumSize=1000} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.cmd.tags from configured configuration GuavaNamedCacheConfiguration{maximumSize=500} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.cmd.tags from builder: CacheBuilder{maximumSize=500} 2021-02-12 12:37:17.022 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - create cache sonia.cache.cmd.branches from configured configuration GuavaNamedCacheConfiguration{maximumSize=500} 2021-02-12 12:37:17.022 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.cmd.branches from builder: CacheBuilder{maximumSize=500} 2021-02-12 12:37:17.023 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.cache.GuavaCacheManager 2021-02-12 12:37:17.026 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.security.AssignedPermission and name: security.xml 2021-02-12 12:37:17.028 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationEntryStore - load configuration from /home/phoenix/scm-ldap-plugin/build/scm-home/config/security.xml 2021-02-12 12:37:17.029 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element AssignedPermission{name=scmadmin, groupPermission=false, permission=PermissionDescriptor{value=*}} to configuration entry store 2021-02-12 12:37:17.032 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.033 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 14 permissions at file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.033 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=*}, PermissionDescriptor{value=repository:read,pull:*}, PermissionDescriptor{value=repository:read,pull,push:*}, PermissionDescriptor{value=repository:*}, PermissionDescriptor{value=repository:create}, PermissionDescriptor{value=user:*}, PermissionDescriptor{value=group:*}, PermissionDescriptor{value=permission:*}, PermissionDescriptor{value=configuration:list}, PermissionDescriptor{value=configuration:read,write:global}, PermissionDescriptor{value=configuration:read,write:*}, PermissionDescriptor{value=repositoryRole:read,write}, PermissionDescriptor{value=plugin:read}, PermissionDescriptor{value=plugin:read,write}] 2021-02-12 12:37:17.033 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.035 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 14 permissions at file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.035 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=*}, PermissionDescriptor{value=repository:read,pull:*}, PermissionDescriptor{value=repository:read,pull,push:*}, PermissionDescriptor{value=repository:*}, PermissionDescriptor{value=repository:create}, PermissionDescriptor{value=user:*}, PermissionDescriptor{value=group:*}, PermissionDescriptor{value=permission:*}, PermissionDescriptor{value=configuration:list}, PermissionDescriptor{value=configuration:read,write:global}, PermissionDescriptor{value=configuration:read,write:*}, PermissionDescriptor{value=repositoryRole:read,write}, PermissionDescriptor{value=plugin:read}, PermissionDescriptor{value=plugin:read,write}] 2021-02-12 12:37:17.035 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.036 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 2 permissions at file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.036 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=configuration:read,write:svn}, PermissionDescriptor{value=repository:svn:*}] 2021-02-12 12:37:17.036 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.036 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 1 permissions at file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.036 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=configuration:read,write:ldap}] 2021-02-12 12:37:17.036 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.037 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 2 permissions at file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.037 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=configuration:read,write:git}, PermissionDescriptor{value=repository:git:*}] 2021-02-12 12:37:17.037 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - read permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.038 [main] [ ] DEBUG sonia.scm.security.DefaultSecuritySystem - found 2 permissions at file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/permissions.xml 2021-02-12 12:37:17.038 [main] [ ] TRACE sonia.scm.security.DefaultSecuritySystem - permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/permissions.xml: [PermissionDescriptor{value=configuration:read,write:hg}, PermissionDescriptor{value=repository:hg:*}] 2021-02-12 12:37:17.041 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.042 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:17.042 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@2f508f3c, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@3ed03652, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@4aedaf61] 2021-02-12 12:37:17.042 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.043 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:17.043 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@3c35c345, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@3681037, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@2459319c] 2021-02-12 12:37:17.043 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.044 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [svn] 2021-02-12 12:37:17.044 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.044 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.045 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [git] 2021-02-12 12:37:17.045 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.045 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.046 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [hg] 2021-02-12 12:37:17.046 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.050 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.repository.xml.XmlRepositoryRoleDatabase and name: repositoryRoles.xml 2021-02-12 12:37:17.050 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.xml.XmlRepositoryRoleDatabase from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/repositoryRoles.xml 2021-02-12 12:37:17.053 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.group.xml.XmlGroupDatabase and name: groups.xml 2021-02-12 12:37:17.053 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.group.xml.XmlGroupDatabase from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/groups.xml 2021-02-12 12:37:17.058 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.auth.ldap.LdapConfig and name: ldap.xml 2021-02-12 12:37:17.058 [main] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.externalGroups 2021-02-12 12:37:17.059 [main] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.authorizing 2021-02-12 12:37:17.062 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.user.xml.XmlUserDatabase and name: users.xml 2021-02-12 12:37:17.062 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.user.xml.XmlUserDatabase from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/users.xml 2021-02-12 12:37:17.068 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.user.DefaultUserManager 2021-02-12 12:37:17.068 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.user.DefaultUserManager 2021-02-12 12:37:17.069 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.group.DefaultGroupManager 2021-02-12 12:37:17.069 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.group.DefaultGroupManager 2021-02-12 12:37:17.071 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.security.SecureKey and name: keys.xml 2021-02-12 12:37:17.072 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationEntryStore - load configuration from /home/phoenix/scm-ldap-plugin/build/scm-home/config/keys.xml 2021-02-12 12:37:17.073 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - add element sonia.scm.security.SecureKey@b8954f14 to configuration entry store 2021-02-12 12:37:17.152 [main] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache AdminRealm.authorizationCache 2021-02-12 12:37:17.152 [main] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - cache AdminRealm.authorizationCache does not exists, creating a new instance from default configuration: GuavaCacheConfiguration{expireAfterAccess=1200, expireAfterWrite=2400, maximumSize=200} 2021-02-12 12:37:17.152 [main] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache AdminRealm.authorizationCache from builder: CacheBuilder{maximumSize=200, expireAfterWrite=2400000000000ns, expireAfterAccess=1200000000000ns} 2021-02-12 12:37:17.153 [main] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache AdminRealm.authorizationCache 2021-02-12 12:37:17.158 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.schedule.CronScheduler 2021-02-12 12:37:17.167 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.repository.GitConfig and name: git.xml 2021-02-12 12:37:17.167 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.repository.GitRepositoryHandler 2021-02-12 12:37:17.167 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.167 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.GitConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/git.xml 2021-02-12 12:37:17.167 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.GitRepositoryHandler 2021-02-12 12:37:17.173 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.repository.HgConfig and name: hg.xml 2021-02-12 12:37:17.178 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.repository.HgRepositoryHandler 2021-02-12 12:37:17.178 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.178 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.HgConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/hg.xml 2021-02-12 12:37:17.180 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script blame.py 2021-02-12 12:37:17.180 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script changelog.py 2021-02-12 12:37:17.180 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script filelog.py 2021-02-12 12:37:17.180 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script log.py 2021-02-12 12:37:17.181 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script util.py 2021-02-12 12:37:17.181 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script scmhooks.py 2021-02-12 12:37:17.181 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script hgweb.py 2021-02-12 12:37:17.181 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script version.py 2021-02-12 12:37:17.181 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.HgRepositoryHandler 2021-02-12 12:37:17.185 [main] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.repository.SvnConfig and name: svn.xml 2021-02-12 12:37:17.199 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.repository.SvnRepositoryHandler 2021-02-12 12:37:17.199 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.199 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.SvnConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/svn.xml 2021-02-12 12:37:17.199 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.SvnRepositoryHandler 2021-02-12 12:37:17.200 [main] [ ] INFO sonia.scm.repository.DefaultRepositoryManager - added RepositoryHandler class sonia.scm.repository.HgRepositoryHandler for type Type{name=hg, displayName=Mercurial} 2021-02-12 12:37:17.200 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.200 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script blame.py 2021-02-12 12:37:17.200 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script changelog.py 2021-02-12 12:37:17.200 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script filelog.py 2021-02-12 12:37:17.200 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script log.py 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script util.py 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script scmhooks.py 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script hgweb.py 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.HgRepositoryHandler - write python script version.py 2021-02-12 12:37:17.201 [main] [ ] INFO sonia.scm.repository.DefaultRepositoryManager - added RepositoryHandler class sonia.scm.repository.SvnRepositoryHandler for type Type{name=svn, displayName=Subversion} 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.SvnConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/svn.xml 2021-02-12 12:37:17.201 [main] [ ] INFO sonia.scm.repository.DefaultRepositoryManager - added RepositoryHandler class sonia.scm.repository.GitRepositoryHandler for type Type{name=git, displayName=Git} 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.repository.AbstractRepositoryHandler - load config from store 2021-02-12 12:37:17.201 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.repository.GitConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/git.xml 2021-02-12 12:37:17.201 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.repository.DefaultRepositoryManager 2021-02-12 12:37:17.202 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.DefaultRepositoryManager 2021-02-12 12:37:17.223 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.repository.GitRepositoryModifyListener 2021-02-12 12:37:17.223 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.user.DefaultUserManager 2021-02-12 12:37:17.223 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.api.v2.resources.GitRepositoryConfigChangeClearRepositoryCacheListener 2021-02-12 12:37:17.223 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.repository.RemoveDeletedRepositoryRole 2021-02-12 12:37:17.223 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.repository.DefaultRepositoryRoleManager 2021-02-12 12:37:17.225 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.226 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:17.226 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@15b82644, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@20576557, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@574cd322] 2021-02-12 12:37:17.226 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.227 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [read, modify, delete, pull, push, permissionRead, permissionWrite, *] 2021-02-12 12:37:17.227 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/WEB-INF/classes/META-INF/scm/repository-permissions.xml: [sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@119c745c, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@a7ad6e5, sonia.scm.security.SystemRepositoryPermissionProvider$RoleDescriptor@3b1ed14b] 2021-02-12 12:37:17.227 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.228 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [svn] 2021-02-12 12:37:17.228 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.228 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.229 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [git] 2021-02-12 12:37:17.229 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.229 [main] [ ] DEBUG sonia.scm.security.SystemRepositoryPermissionProvider - read repository permission descriptor from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml 2021-02-12 12:37:17.229 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository permissions from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [hg] 2021-02-12 12:37:17.229 [main] [ ] TRACE sonia.scm.security.SystemRepositoryPermissionProvider - repository roles from file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/classes/META-INF/scm/repository-permissions.xml: [] 2021-02-12 12:37:17.229 [main] [ ] TRACE sonia.scm.lifecycle.modules.ScmInitializerModule - initialize initable class sonia.scm.repository.DefaultRepositoryRoleManager 2021-02-12 12:37:17.230 [main] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.DefaultRepositoryRoleManager 2021-02-12 12:37:17.230 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.security.AuthorizationChangedEventProducer 2021-02-12 12:37:17.230 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.repository.LastModifiedUpdateListener 2021-02-12 12:37:17.230 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.user.AnonymousUserDeletionEventHandler 2021-02-12 12:37:17.230 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.debug.DebugHook 2021-02-12 12:37:17.232 [main] [ ] INFO sonia.scm.lifecycle.modules.EagerSingletonModule - initialize eager singleton sonia.scm.config.ScmConfigurationChangedListener 2021-02-12 12:37:17.270 [main] [ ] TRACE sonia.scm.web.security.DefaultAdministrationContext - bind shiro security manager to current thread 2021-02-12 12:37:17.274 [main] [ ] INFO sonia.scm.web.security.DefaultAdministrationContext - execute action sonia.scm.lifecycle.SetupContextListener$SetupAction in administration context 2021-02-12 12:37:17.276 [main] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.auth.ldap.LdapConfig from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/ldap.xml 2021-02-12 12:37:17.276 [main] [ ] DEBUG sonia.scm.auth.ldap.LdapGroupResolver - ldap is disabled, returning empty set of groups 2021-02-12 12:37:17.277 [main] [ ] DEBUG sonia.scm.group.DefaultGroupCollector - collected following groups for principal scmsystem: [_authenticated] 2021-02-12 12:37:17.277 [main] [ ] TRACE sonia.scm.security.DefaultAuthorizationCollector - collect AuthorizationInfo for user scmsystem 2021-02-12 12:37:17.278 [main] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get all items from configuration store 2021-02-12 12:37:17.279 [main] [ ] TRACE sonia.scm.security.DefaultRealm - principal does not contain scope information, returning all permissions 2021-02-12 12:37:17.279 [main] [ ] TRACE sonia.scm.security.DefaultRealm - authorization summary: username : scmsystem roles : - user scope : permissions: - group:autocomplete - user:changePassword:scmsystem - user:read:scmsystem - user:autocomplete 2021-02-12 12:37:17.279 [main] [ ] INFO sonia.scm.web.security.AdministrationContextRealm - assign admin permissions to admin context user scmsystem 2021-02-12 12:37:17.279 [main] [ ] DEBUG sonia.scm.group.DefaultGroupCollector - collected following groups for principal scmsystem: [_authenticated] 2021-02-12 12:37:17.287 [main] [ ] TRACE sonia.scm.security.DefaultAuthorizationCollector - retrieve AuthorizationInfo for user scmsystem from cache 2021-02-12 12:37:17.287 [main] [ ] TRACE sonia.scm.security.DefaultRealm - principal does not contain scope information, returning all permissions 2021-02-12 12:37:17.287 [main] [ ] TRACE sonia.scm.security.DefaultRealm - authorization summary: username : scmsystem roles : - user scope : permissions: - group:autocomplete - user:changePassword:scmsystem - user:read:scmsystem - user:autocomplete 2021-02-12 12:37:17.287 [main] [ ] TRACE sonia.scm.web.security.DefaultAdministrationContext - restore current thread state 2021-02-12 12:37:17.287 [main] [ ] DEBUG sonia.scm.web.GitContextListener - register scm transport protocol 2021-02-12 12:37:17.306 [main] [ ] TRACE sonia.scm.web.security.DefaultAdministrationContext - bind shiro security manager to current thread 2021-02-12 12:37:17.307 [main] [ ] INFO sonia.scm.web.security.DefaultAdministrationContext - execute action sonia.scm.repository.HealthCheckContextListener$HealthCheckStartupAction in administration context 2021-02-12 12:37:17.307 [main] [ ] DEBUG sonia.scm.repository.HealthChecker - check health of all repositories 2021-02-12 12:37:17.308 [main] [ ] TRACE sonia.scm.web.security.DefaultAdministrationContext - restore current thread state ==> scm-server started successfully and is accessible at: ==> http://localhost:8081/scm 2021-02-12 12:37:18.079 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.scm.user-agent 2021-02-12 12:37:18.079 [qtp77269878-16] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - cache sonia.scm.user-agent does not exists, creating a new instance from default configuration: GuavaCacheConfiguration{expireAfterAccess=1200, expireAfterWrite=2400, maximumSize=200} 2021-02-12 12:37:18.079 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.scm.user-agent from builder: CacheBuilder{maximumSize=200, expireAfterWrite=2400000000000ns, expireAfterAccess=1200000000000ns} 2021-02-12 12:37:18.088 [qtp77269878-16] [ ] DEBUG sonia.scm.store.FileBasedStoreFactory - create store with type: class sonia.scm.update.repository.RepositoryMigrationPlan and name: migration-plan.xml 2021-02-12 12:37:18.088 [qtp77269878-16] [ ] DEBUG sonia.scm.store.JAXBConfigurationStore - load class sonia.scm.update.repository.RepositoryMigrationPlan from store /home/phoenix/scm-ldap-plugin/build/scm-home/config/migration-plan.xml 2021-02-12 12:37:18.101 [qtp77269878-16] [ ] INFO sonia.scm.lifecycle.modules.ResteasyAllInOneServletDispatcher - init resteasy 2021-02-12 12:37:18.324 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.ConcurrentModificationExceptionMapper 2021-02-12 12:37:18.326 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.FallbackExceptionMapper 2021-02-12 12:37:18.326 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.InvalidFormatExceptionMapper 2021-02-12 12:37:18.327 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.AuthorizationExceptionMapper 2021-02-12 12:37:18.327 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.FieldContainerResponseFilter 2021-02-12 12:37:18.327 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.JavaxValidationExceptionMapper 2021-02-12 12:37:18.328 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.UriInfoFilter 2021-02-12 12:37:18.328 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.RestActionUploadResultMessageWriter 2021-02-12 12:37:18.329 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.AlreadyExistsExceptionMapper 2021-02-12 12:37:18.329 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.ScmSecurityExceptionMapper 2021-02-12 12:37:18.329 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.CacheControlResponseFilter 2021-02-12 12:37:18.329 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.AuthenticationExceptionMapper 2021-02-12 12:37:18.330 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.NotSupportedExceptionMapper 2021-02-12 12:37:18.330 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.DisabledAccountExceptionMapper 2021-02-12 12:37:18.330 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.security.SecurityRequestFilter 2021-02-12 12:37:18.331 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.NotAuthorizedExceptionMapper 2021-02-12 12:37:18.331 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.ContextualFallbackExceptionMapper 2021-02-12 12:37:18.332 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.ScmConstraintValidationExceptionMapper 2021-02-12 12:37:18.332 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.TemplateEngineViewable 2021-02-12 12:37:18.338 [qtp77269878-16] [ ] DEBUG sonia.scm.template.TemplateEngineFactory - register Type{name=mustache, displayName=Mustache} as default template engine 2021-02-12 12:37:18.338 [qtp77269878-16] [ ] DEBUG sonia.scm.template.TemplateEngineFactory - register template engin Type{name=mustache, displayName=Mustache} 2021-02-12 12:37:18.339 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.filter.GZipResponseFilter 2021-02-12 12:37:18.339 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.JsonMarshallingResponseFilter 2021-02-12 12:37:18.444 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.NotFoundExceptionMapper 2021-02-12 12:37:18.444 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.JSONContextResolver 2021-02-12 12:37:18.445 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.JaxNotFoundExceptionMapper 2021-02-12 12:37:18.445 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.v2.resources.InternalRepositoryExceptionMapper 2021-02-12 12:37:18.445 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.NotAllowedExceptionMapper 2021-02-12 12:37:18.446 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.JsonParseExceptionMapper 2021-02-12 12:37:18.446 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.BadRequestExceptionMapper 2021-02-12 12:37:18.446 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011025: registering provider instance for sonia.scm.api.rest.ExcessiveAttemptsExceptionMapper 2021-02-12 12:37:18.447 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.RepositoryVerbResource 2021-02-12 12:37:18.545 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.validation.i18n - RESTEASY008550: Unable to find CDI supporting ValidatorFactory. Using default ValidatorFactory 2021-02-12 12:37:18.652 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.auth.ldap.resource.LdapConfigResource 2021-02-12 12:37:18.670 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.legacy.LegacyRepositoryService 2021-02-12 12:37:18.674 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.GroupRootResource 2021-02-12 12:37:18.676 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.GitConfigResource 2021-02-12 12:37:18.678 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.AuthenticationResource 2021-02-12 12:37:18.686 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.debug.DebugResource 2021-02-12 12:37:18.688 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.SvnConfigResource 2021-02-12 12:37:18.690 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.IndexResource 2021-02-12 12:37:18.691 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.PluginRootResource 2021-02-12 12:37:18.691 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.RepositoryRootResource 2021-02-12 12:37:18.692 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.GlobalPermissionResource 2021-02-12 12:37:18.693 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.NamespaceStrategyResource 2021-02-12 12:37:18.694 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.RepositoryTypeRootResource 2021-02-12 12:37:18.695 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.ConfigResource 2021-02-12 12:37:18.696 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.MeResource 2021-02-12 12:37:18.699 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.UIRootResource 2021-02-12 12:37:18.699 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.AutoCompleteResource 2021-02-12 12:37:18.705 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.HgConfigResource 2021-02-12 12:37:18.707 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.RepositoryRoleRootResource 2021-02-12 12:37:18.708 [qtp77269878-16] [ ] INFO org.jboss.resteasy.plugins.guice.i18n - RESTEASY011020: registering factory for sonia.scm.api.v2.resources.UserRootResource 2021-02-12 12:37:18.709 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.plugins.translations 2021-02-12 12:37:18.709 [qtp77269878-16] [ ] DEBUG sonia.scm.cache.GuavaCacheManager - cache sonia.cache.plugins.translations does not exists, creating a new instance from default configuration: GuavaCacheConfiguration{expireAfterAccess=1200, expireAfterWrite=2400, maximumSize=200} 2021-02-12 12:37:18.709 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCaches - create new cache sonia.cache.plugins.translations from builder: CacheBuilder{maximumSize=200, expireAfterWrite=2400000000000ns, expireAfterAccess=1200000000000ns} 2021-02-12 12:37:18.710 [qtp77269878-16] [ ] DEBUG sonia.scm.lifecycle.modules.CloseableModule - register closable class sonia.scm.repository.DefaultSyncAsyncExecutorProvider 2021-02-12 12:37:18.713 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.cmd.blame 2021-02-12 12:37:18.713 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.cmd.browse 2021-02-12 12:37:18.713 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.cmd.log 2021-02-12 12:37:18.713 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.cmd.tags 2021-02-12 12:37:18.713 [qtp77269878-16] [ ] TRACE sonia.scm.cache.GuavaCacheManager - try to retrieve cache sonia.cache.cmd.branches 2021-02-12 12:37:18.731 [qtp77269878-16] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key DYSOnoPNL1 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.WebResourceServlet - try to load / 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin/webapp 2021-02-12 12:37:18.733 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.WebResourceServlet - could not find / -- dispatch 2021-02-12 12:37:18.734 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.template.MustacheTemplateEngine - try to find mustache template at /index.mustache 2021-02-12 12:37:18.734 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.template.ServletMustacheFactory - try to find resource for /index.mustache 2021-02-12 12:37:18.735 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.template.ServletMustacheFactory - found resource for /index.mustache, return reader 2021-02-12 12:37:18.739 [qtp77269878-16] [DYSOnoPNL1] TRACE sonia.scm.template.MustacheTemplateEngine - return mustache template for /index.mustache 2021-02-12 12:37:18.740 [qtp77269878-16] [DYSOnoPNL1] DEBUG sonia.scm.template.MustacheTemplate - render mustache template at /index.mustache 2021-02-12 12:37:18.766 [qtp77269878-14] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 7gSOnoPNt2 2021-02-12 12:37:18.766 [qtp77269878-12] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key CMSOnoPNu3 2021-02-12 12:37:18.766 [qtp77269878-14] [7gSOnoPNt2] TRACE sonia.scm.WebResourceServlet - try to load /assets/vendors~webapp.bundle.js 2021-02-12 12:37:18.766 [qtp77269878-12] [CMSOnoPNu3] TRACE sonia.scm.WebResourceServlet - try to load /assets/ui-styles.css 2021-02-12 12:37:18.766 [qtp77269878-12] [CMSOnoPNu3] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /assets/ui-styles.css at ServletContext 2021-02-12 12:37:18.766 [qtp77269878-14] [7gSOnoPNt2] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /assets/vendors~webapp.bundle.js at ServletContext 2021-02-12 12:37:18.767 [qtp77269878-28] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 2cSOnoPNv4 2021-02-12 12:37:18.767 [qtp77269878-31] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 85SOnoPNv5 2021-02-12 12:37:18.768 [qtp77269878-28] [2cSOnoPNv4] TRACE sonia.scm.WebResourceServlet - try to load /assets/runtime.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-31] [85SOnoPNv5] TRACE sonia.scm.WebResourceServlet - try to load /assets/webapp.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-12] [CMSOnoPNu3] TRACE sonia.scm.WebResourceServlet - found /assets/ui-styles.css -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/assets/ui-styles.css 2021-02-12 12:37:18.768 [qtp77269878-14] [7gSOnoPNt2] TRACE sonia.scm.WebResourceServlet - found /assets/vendors~webapp.bundle.js -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/assets/vendors~webapp.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-12] [CMSOnoPNu3] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/ui-styles.css 2021-02-12 12:37:18.768 [qtp77269878-14] [7gSOnoPNt2] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/vendors~webapp.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-28] [2cSOnoPNv4] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /assets/runtime.bundle.js at ServletContext 2021-02-12 12:37:18.768 [qtp77269878-31] [85SOnoPNv5] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /assets/webapp.bundle.js at ServletContext 2021-02-12 12:37:18.768 [qtp77269878-28] [2cSOnoPNv4] TRACE sonia.scm.WebResourceServlet - found /assets/runtime.bundle.js -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/assets/runtime.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-28] [2cSOnoPNv4] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/runtime.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-31] [85SOnoPNv5] TRACE sonia.scm.WebResourceServlet - found /assets/webapp.bundle.js -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/assets/webapp.bundle.js 2021-02-12 12:37:18.768 [qtp77269878-31] [85SOnoPNv5] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/webapp.bundle.js 2021-02-12 12:37:19.315 [qtp77269878-18] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - generated web token class sonia.scm.security.BearerToken from generator class sonia.scm.web.CookieBearerWebTokenGenerator 2021-02-12 12:37:19.315 [qtp77269878-18] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - found authentication token on request, start authentication 2021-02-12 12:37:19.315 [qtp77269878-18] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - found basic authorization header, start authentication 2021-02-12 12:37:19.315 [qtp77269878-11] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key A5SOnoPWl6 2021-02-12 12:37:19.315 [qtp77269878-11] [A5SOnoPWl6] TRACE sonia.scm.WebResourceServlet - try to load /locales/en/commons.json 2021-02-12 12:37:19.315 [qtp77269878-11] [A5SOnoPWl6] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /locales/en/commons.json at ServletContext 2021-02-12 12:37:19.315 [qtp77269878-11] [A5SOnoPWl6] TRACE sonia.scm.WebResourceServlet - found /locales/en/commons.json -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/locales/en/commons.json 2021-02-12 12:37:19.315 [qtp77269878-11] [A5SOnoPWl6] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/locales/en/commons.json 2021-02-12 12:37:19.316 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.DefaultRealm@107f4980] does not support token sonia.scm.security.BearerToken@5359bc25. Skipping realm. 2021-02-12 12:37:19.316 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.web.security.AdministrationContextRealm@5477a1ca] does not support token sonia.scm.security.BearerToken@5359bc25. Skipping realm. 2021-02-12 12:37:19.316 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.AnonymousRealm@1d540566] does not support token sonia.scm.security.BearerToken@5359bc25. Skipping realm. 2021-02-12 12:37:19.316 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.auth.ldap.LdapRealm@6014a9ba] does not support token sonia.scm.security.BearerToken@5359bc25. Skipping realm. 2021-02-12 12:37:19.316 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.legacy.LegacyRealm@acdcf71] does not support token sonia.scm.security.BearerToken@5359bc25. Skipping realm. 2021-02-12 12:37:19.317 [qtp77269878-29] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key EqSOnoPWn7 2021-02-12 12:37:19.318 [qtp77269878-29] [EqSOnoPWn7] TRACE sonia.scm.WebResourceServlet - try to load /images/loading.svg 2021-02-12 12:37:19.318 [qtp77269878-29] [EqSOnoPWn7] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /images/loading.svg at ServletContext 2021-02-12 12:37:19.318 [qtp77269878-29] [EqSOnoPWn7] TRACE sonia.scm.WebResourceServlet - found /images/loading.svg -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/images/loading.svg 2021-02-12 12:37:19.318 [qtp77269878-29] [EqSOnoPWn7] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/images/loading.svg 2021-02-12 12:37:19.319 [qtp77269878-17] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 2VSOnoPWp8 2021-02-12 12:37:19.319 [qtp77269878-17] [2VSOnoPWp8] TRACE sonia.scm.WebResourceServlet - try to load /assets/tokenizer.bundle.worker.js 2021-02-12 12:37:19.320 [qtp77269878-17] [2VSOnoPWp8] TRACE sonia.scm.plugin.DefaultUberWebResourceLoader - found path /assets/tokenizer.bundle.worker.js at ServletContext 2021-02-12 12:37:19.320 [qtp77269878-17] [2VSOnoPWp8] TRACE sonia.scm.WebResourceServlet - found /assets/tokenizer.bundle.worker.js -- serve as resource file:/tmp/jetty-0_0_0_0-8081-scm-webapp-2_0_0_war-_scm-any-1582061646491569343/webapp/assets/tokenizer.bundle.worker.js 2021-02-12 12:37:19.320 [qtp77269878-17] [2VSOnoPWp8] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/tokenizer.bundle.worker.js 2021-02-12 12:37:19.368 [qtp77269878-18] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item scmadmin from configuration store 2021-02-12 12:37:19.374 [qtp77269878-18] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.BearerRealm@77d680e6] threw an exception during a multi-realm authentication attempt: org.apache.shiro.authc.AuthenticationException: signature is invalid at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:75) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:43) at sonia.scm.security.BearerRealm.doGetAuthenticationInfo(BearerRealm.java:95) at org.apache.shiro.realm.AuthenticatingRealm.getAuthenticationInfo(AuthenticatingRealm.java:571) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doMultiRealmAuthentication(ModularRealmAuthenticator.java:225) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doAuthenticate(ModularRealmAuthenticator.java:275) at org.apache.shiro.authc.AbstractAuthenticator.authenticate(AbstractAuthenticator.java:198) at org.apache.shiro.mgt.AuthenticatingSecurityManager.authenticate(AuthenticatingSecurityManager.java:106) at org.apache.shiro.mgt.DefaultSecurityManager.login(DefaultSecurityManager.java:275) at org.apache.shiro.subject.support.DelegatingSubject.login(DelegatingSubject.java:260) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:248) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) Caused by: io.jsonwebtoken.security.SignatureException: JWT signature does not match locally computed signature. JWT validity cannot be asserted and should not be trusted. at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:411) at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:541) at io.jsonwebtoken.impl.DefaultJwtParser.parseClaimsJws(DefaultJwtParser.java:601) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:67) ... 65 common frames omitted 2021-02-12 12:37:19.375 [qtp77269878-18] [ ] WARN sonia.scm.web.filter.AuthenticationFilter - authentication failed org.apache.shiro.authc.AuthenticationException: Authentication token of type [class sonia.scm.security.BearerToken] could not be authenticated by any configured realms. Please ensure that at least one realm can authenticate these tokens. at org.apache.shiro.authc.pam.AtLeastOneSuccessfulStrategy.afterAllAttempts(AtLeastOneSuccessfulStrategy.java:58) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doMultiRealmAuthentication(ModularRealmAuthenticator.java:241) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doAuthenticate(ModularRealmAuthenticator.java:275) at org.apache.shiro.authc.AbstractAuthenticator.authenticate(AbstractAuthenticator.java:198) at org.apache.shiro.mgt.AuthenticatingSecurityManager.authenticate(AuthenticatingSecurityManager.java:106) at org.apache.shiro.mgt.DefaultSecurityManager.login(DefaultSecurityManager.java:275) at org.apache.shiro.subject.support.DelegatingSubject.login(DelegatingSubject.java:260) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:248) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) 2021-02-12 12:37:19.375 [qtp77269878-18] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key CjSOnoPXj9 2021-02-12 12:37:19.377 [qtp77269878-18] [CjSOnoPXj9] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item scmadmin from configuration store 2021-02-12 12:37:19.377 [qtp77269878-18] [CjSOnoPXj9] TRACE sonia.scm.web.security.TokenRefreshFilter - could not resolve token org.apache.shiro.authc.AuthenticationException: signature is invalid at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:75) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:43) at sonia.scm.web.security.TokenRefreshFilter.examineToken(TokenRefreshFilter.java:94) at sonia.scm.web.security.TokenRefreshFilter.lambda$doFilter$0(TokenRefreshFilter.java:77) at java.util.Optional.ifPresent(Optional.java:159) at sonia.scm.web.security.TokenRefreshFilter.doFilter(TokenRefreshFilter.java:77) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.MDCFilter.doFilter(MDCFilter.java:105) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.web.security.ApiAuthenticationFilter.handleUnauthorized(ApiAuthenticationFilter.java:119) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:254) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) Caused by: io.jsonwebtoken.security.SignatureException: JWT signature does not match locally computed signature. JWT validity cannot be asserted and should not be trusted. at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:411) at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:541) at io.jsonwebtoken.impl.DefaultJwtParser.parseClaimsJws(DefaultJwtParser.java:601) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:67) ... 67 common frames omitted 2021-02-12 12:37:19.401 [qtp77269878-18] [CjSOnoPXj9] DEBUG sonia.scm.security.SecurityRequestFilter - allowed unauthenticated request to method public sonia.scm.api.v2.resources.IndexDto sonia.scm.api.v2.resources.IndexResource.getIndex() 2021-02-12 12:37:19.536 [qtp77269878-18] [CjSOnoPXj9] TRACE sonia.scm.api.v2.CacheControlResponseFilter - add no-cache header to response 2021-02-12 12:37:19.538 [qtp77269878-18] [CjSOnoPXj9] TRACE sonia.scm.filter.GZipResponseFilter - compress output with gzip 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - generated web token class sonia.scm.security.BearerToken from generator class sonia.scm.web.CookieBearerWebTokenGenerator 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - found authentication token on request, start authentication 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] TRACE sonia.scm.web.filter.AuthenticationFilter - found basic authorization header, start authentication 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.DefaultRealm@107f4980] does not support token sonia.scm.security.BearerToken@2fab372b. Skipping realm. 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.web.security.AdministrationContextRealm@5477a1ca] does not support token sonia.scm.security.BearerToken@2fab372b. Skipping realm. 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.AnonymousRealm@1d540566] does not support token sonia.scm.security.BearerToken@2fab372b. Skipping realm. 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.auth.ldap.LdapRealm@6014a9ba] does not support token sonia.scm.security.BearerToken@2fab372b. Skipping realm. 2021-02-12 12:37:19.559 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.legacy.LegacyRealm@acdcf71] does not support token sonia.scm.security.BearerToken@2fab372b. Skipping realm. 2021-02-12 12:37:19.560 [qtp77269878-30] [ ] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item scmadmin from configuration store 2021-02-12 12:37:19.561 [qtp77269878-30] [ ] DEBUG org.apache.shiro.authc.pam.ModularRealmAuthenticator - Realm [sonia.scm.security.BearerRealm@77d680e6] threw an exception during a multi-realm authentication attempt: org.apache.shiro.authc.AuthenticationException: signature is invalid at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:75) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:43) at sonia.scm.security.BearerRealm.doGetAuthenticationInfo(BearerRealm.java:95) at org.apache.shiro.realm.AuthenticatingRealm.getAuthenticationInfo(AuthenticatingRealm.java:571) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doMultiRealmAuthentication(ModularRealmAuthenticator.java:225) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doAuthenticate(ModularRealmAuthenticator.java:275) at org.apache.shiro.authc.AbstractAuthenticator.authenticate(AbstractAuthenticator.java:198) at org.apache.shiro.mgt.AuthenticatingSecurityManager.authenticate(AuthenticatingSecurityManager.java:106) at org.apache.shiro.mgt.DefaultSecurityManager.login(DefaultSecurityManager.java:275) at org.apache.shiro.subject.support.DelegatingSubject.login(DelegatingSubject.java:260) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:248) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) Caused by: io.jsonwebtoken.security.SignatureException: JWT signature does not match locally computed signature. JWT validity cannot be asserted and should not be trusted. at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:411) at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:541) at io.jsonwebtoken.impl.DefaultJwtParser.parseClaimsJws(DefaultJwtParser.java:601) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:67) ... 65 common frames omitted 2021-02-12 12:37:19.561 [qtp77269878-30] [ ] WARN sonia.scm.web.filter.AuthenticationFilter - authentication failed org.apache.shiro.authc.AuthenticationException: Authentication token of type [class sonia.scm.security.BearerToken] could not be authenticated by any configured realms. Please ensure that at least one realm can authenticate these tokens. at org.apache.shiro.authc.pam.AtLeastOneSuccessfulStrategy.afterAllAttempts(AtLeastOneSuccessfulStrategy.java:58) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doMultiRealmAuthentication(ModularRealmAuthenticator.java:241) at org.apache.shiro.authc.pam.ModularRealmAuthenticator.doAuthenticate(ModularRealmAuthenticator.java:275) at org.apache.shiro.authc.AbstractAuthenticator.authenticate(AbstractAuthenticator.java:198) at org.apache.shiro.mgt.AuthenticatingSecurityManager.authenticate(AuthenticatingSecurityManager.java:106) at org.apache.shiro.mgt.DefaultSecurityManager.login(DefaultSecurityManager.java:275) at org.apache.shiro.subject.support.DelegatingSubject.login(DelegatingSubject.java:260) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:248) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) 2021-02-12 12:37:19.561 [qtp77269878-30] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 21SOnoPajA 2021-02-12 12:37:19.562 [qtp77269878-30] [21SOnoPajA] TRACE sonia.scm.store.JAXBConfigurationEntryStore - get item scmadmin from configuration store 2021-02-12 12:37:19.563 [qtp77269878-30] [21SOnoPajA] TRACE sonia.scm.web.security.TokenRefreshFilter - could not resolve token org.apache.shiro.authc.AuthenticationException: signature is invalid at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:75) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:43) at sonia.scm.web.security.TokenRefreshFilter.examineToken(TokenRefreshFilter.java:94) at sonia.scm.web.security.TokenRefreshFilter.lambda$doFilter$0(TokenRefreshFilter.java:77) at java.util.Optional.ifPresent(Optional.java:159) at sonia.scm.web.security.TokenRefreshFilter.doFilter(TokenRefreshFilter.java:77) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.MDCFilter.doFilter(MDCFilter.java:105) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.web.security.ApiAuthenticationFilter.handleUnauthorized(ApiAuthenticationFilter.java:119) at sonia.scm.web.filter.AuthenticationFilter.handleAuthentication(AuthenticationFilter.java:254) at sonia.scm.web.filter.AuthenticationFilter.doFilter(AuthenticationFilter.java:114) at sonia.scm.web.security.ApiAuthenticationFilter.doFilter(ApiAuthenticationFilter.java:99) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at sonia.scm.filter.BaseUrlFilter.doFilter(BaseUrlFilter.java:109) at sonia.scm.web.filter.HttpFilter.doFilter(HttpFilter.java:93) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:44) at org.apache.shiro.web.servlet.AdviceFilter.executeChain(AdviceFilter.java:108) at org.apache.shiro.web.servlet.AdviceFilter.doFilterInternal(AdviceFilter.java:137) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at org.apache.shiro.guice.web.SimpleFilterChain.doFilter(SimpleFilterChain.java:41) at org.apache.shiro.web.servlet.AbstractShiroFilter.executeChain(AbstractShiroFilter.java:449) at org.apache.shiro.web.servlet.AbstractShiroFilter$1.call(AbstractShiroFilter.java:365) at org.apache.shiro.subject.support.SubjectCallable.doCall(SubjectCallable.java:90) at org.apache.shiro.subject.support.SubjectCallable.call(SubjectCallable.java:83) at org.apache.shiro.subject.support.DelegatingSubject.execute(DelegatingSubject.java:387) at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:362) at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:121) at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:133) at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:201) at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:602) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) at org.eclipse.jetty.server.Server.handle(Server.java:516) at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:375) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) at java.lang.Thread.run(Thread.java:748) Caused by: io.jsonwebtoken.security.SignatureException: JWT signature does not match locally computed signature. JWT validity cannot be asserted and should not be trusted. at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:411) at io.jsonwebtoken.impl.DefaultJwtParser.parse(DefaultJwtParser.java:541) at io.jsonwebtoken.impl.DefaultJwtParser.parseClaimsJws(DefaultJwtParser.java:601) at sonia.scm.security.JwtAccessTokenResolver.resolve(JwtAccessTokenResolver.java:67) ... 67 common frames omitted 2021-02-12 12:37:19.567 [qtp77269878-30] [21SOnoPajA] DEBUG sonia.scm.security.SecurityRequestFilter - allowed unauthenticated request to method public javax.ws.rs.core.Response sonia.scm.api.v2.resources.UIPluginResource.getInstalledPlugins() 2021-02-12 12:37:19.583 [qtp77269878-30] [21SOnoPajA] TRACE sonia.scm.api.v2.CacheControlResponseFilter - add no-cache header to response 2021-02-12 12:37:19.583 [qtp77269878-30] [21SOnoPajA] TRACE sonia.scm.filter.GZipResponseFilter - compress output with gzip 2021-02-12 12:37:19.594 [qtp77269878-16] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 75SOnoPbGB 2021-02-12 12:37:19.594 [qtp77269878-18] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 46SOnoPbGD 2021-02-12 12:37:19.594 [qtp77269878-14] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 8GSOnoPbGC 2021-02-12 12:37:19.594 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.WebResourceServlet - try to load /assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.594 [qtp77269878-14] [8GSOnoPbGC] TRACE sonia.scm.WebResourceServlet - try to load /assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.595 [qtp77269878-13] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 45SOnoPbHE 2021-02-12 12:37:19.594 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.WebResourceServlet - try to load /assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.WebResourceServlet - try to load /assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-14] [8GSOnoPbGC] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.596 [qtp77269878-30] [ ] TRACE sonia.scm.security.DefaultKeyGenerator - create new key 65SOnoPbIF 2021-02-12 12:37:19.598 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-14] [8GSOnoPbGC] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp/assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-14] [8GSOnoPbGC] TRACE sonia.scm.plugin.PathWebResourceLoader - found path /assets/scm-git-plugin.bundle.js at /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp/assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp/assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-14] [8GSOnoPbGC] TRACE sonia.scm.WebResourceServlet - found /assets/scm-git-plugin.bundle.js -- serve as resource file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp/assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp/assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-14] [8GSOnoPbGC] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/scm-git-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.plugin.PathWebResourceLoader - found path /assets/scm-hg-plugin.bundle.js at /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-30] [65SOnoPbIF] TRACE sonia.scm.WebResourceServlet - try to load /assets/scm-svn-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp/assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.598 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-ldap-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-16] [75SOnoPbGB] TRACE sonia.scm.WebResourceServlet - found /assets/scm-hg-plugin.bundle.js -- serve as resource file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin/webapp/assets/scm-ldap-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-git-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-16] [75SOnoPbGB] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/scm-hg-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.WebResourceServlet - could not find /assets/scm-ldap-plugin.bundle.js -- dispatch 2021-02-12 12:37:19.599 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.template.MustacheTemplateEngine - try to find mustache template at /index.mustache 2021-02-12 12:37:19.599 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.plugin.PathWebResourceLoader - could not find file /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-hg-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.plugin.PathWebResourceLoader - found path /assets/scm-legacy-plugin.bundle.js at /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-13] [45SOnoPbHE] TRACE sonia.scm.WebResourceServlet - found /assets/scm-legacy-plugin.bundle.js -- serve as resource file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-legacy-plugin/webapp/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-13] [45SOnoPbHE] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/scm-legacy-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-30] [65SOnoPbIF] TRACE sonia.scm.plugin.PathWebResourceLoader - found path /assets/scm-svn-plugin.bundle.js at /home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-svn-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-30] [65SOnoPbIF] TRACE sonia.scm.WebResourceServlet - found /assets/scm-svn-plugin.bundle.js -- serve as resource file:/home/phoenix/scm-ldap-plugin/build/scm-home/plugins/scm-svn-plugin/webapp/assets/scm-svn-plugin.bundle.js 2021-02-12 12:37:19.599 [qtp77269878-30] [65SOnoPbIF] DEBUG sonia.scm.WebResourceServlet - using sender to serve /scm/assets/scm-svn-plugin.bundle.js 2021-02-12 12:37:19.602 [qtp77269878-18] [46SOnoPbGD] TRACE sonia.scm.template.MustacheTemplateEngine - return mustache template for /index.mustache 2021-02-12 12:37:19.604 [qtp77269878-18] [46SOnoPbGD] DEBUG sonia.scm.template.MustacheTemplate - render mustache template at /index.mustache ```

Further information like screenshots

[sdorra]

I've fixed the run task on the develop branch.