Skip to content

Instantly share code, notes, and snippets.

@gamemaker1

gamemaker1/registry-server.log Secret

Last active October 2, 2021 11:31
Show Gist options
  • Save gamemaker1/48132a2e2c69ff3de13fd3b0a27f09fa to your computer and use it in GitHub Desktop.
Save gamemaker1/48132a2e2c69ff3de13fd3b0a27f09fa to your computer and use it in GitHub Desktop.
Download ZIP
Registry logs
Raw
registry-server.log
___ ____ _
/ _ \ _ __ ___ _ __ / ___| __ _ | |__ ___ _ __
| | | | | '_ \ / _ \ | '_ \ \___ \ / _` | | '_ \ / _ \ | '__|
| |_| | | |_) | | __/ | | | | ___) | | (_| | | |_) | | __/ | |
\___/ | .__/ \___| |_| |_| |____/ \__,_| |_.__/ \___| |_|
|_|
:: Spring Boot (v2.3.12.RELEASE) ::
16:57:14.680 [main] INFO o.s.boot.loader.JarLauncher:55 - Starting JarLauncher v2.0.3 on posfet with PID 8698 (/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar started by gamemaker1 in /home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java)
16:57:14.679 [background-preinit] DEBUG org.jboss.logging:152 - Logging Provider: org.jboss.logging.Log4j2LoggerProvider
16:57:14.682 [background-preinit] INFO o.h.validator.internal.util.Version:21 - HV000001: Hibernate Validator 6.1.7.Final
16:57:14.682 [main] INFO o.s.boot.loader.JarLauncher:652 - The following profiles are active: dev
16:57:14.704 [background-preinit] DEBUG o.h.v.i.x.config.ValidationXmlParser:120 - Trying to load META-INF/validation.xml for XML based Validator configuration.
16:57:14.707 [background-preinit] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:60 - Trying to load META-INF/validation.xml via TCCL
16:57:14.707 [background-preinit] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:66 - Trying to load META-INF/validation.xml via Hibernate Validator's class loader
16:57:14.708 [background-preinit] DEBUG o.h.v.i.x.config.ValidationXmlParser:127 - No META-INF/validation.xml found. Using annotation based configuration only.
16:57:14.738 [background-preinit] DEBUG o.h.v.i.e.r.TraversableResolvers:60 - Cannot find javax.persistence.Persistence on classpath. Assuming non JPA 2 environment. All properties will per default be traversable.
16:57:14.790 [background-preinit] DEBUG o.h.v.m.ResourceBundleMessageInterpolator:172 - Loaded expression factory via original TCCL
16:57:15.121 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:300 - HV000252: Using org.hibernate.validator.internal.engine.DefaultPropertyNodeNameProvider as property node name provider.
16:57:15.147 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:375 - HV000234: Using org.hibernate.validator.messageinterpolation.ResourceBundleMessageInterpolator as ValidatorFactory-scoped message interpolator.
16:57:15.147 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:376 - HV000234: Using org.hibernate.validator.internal.engine.resolver.TraverseAllTraversableResolver as ValidatorFactory-scoped traversable resolver.
16:57:15.148 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:377 - HV000234: Using org.hibernate.validator.internal.util.ExecutableParameterNameProvider as ValidatorFactory-scoped parameter name provider.
16:57:15.148 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:378 - HV000234: Using org.hibernate.validator.internal.engine.DefaultClockProvider as ValidatorFactory-scoped clock provider.
16:57:15.148 [background-preinit] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:379 - HV000234: Using org.hibernate.validator.internal.engine.scripting.DefaultScriptEvaluatorFactory as ValidatorFactory-scoped script evaluator factory.
16:57:15.414 [background-preinit] WARN o.s.h.c.j.Jackson2ObjectMapperBuilder:127 - For Jackson Kotlin classes support please add "com.fasterxml.jackson.module:jackson-module-kotlin" to the classpath
io.opensaber.actors.AuditActor not found.
ElasticSearchActor -> Actor[akka://opensaber-actors/user/ElasticSearchActor#-1349506508]
OSActor -> Actor[akka://opensaber-actors/user/OSActor#-1822749020]
Indexer -> Actor[akka://opensaber-actors/user/Indexer#-590415820]
SBRouter -> Actor[akka://opensaber-actors/user/SBRouter#803091237]
AutoAttestorActor -> Actor[akka://opensaber-actors/user/AutoAttestorActor#167860161]
NotificationActor -> Actor[akka://opensaber-actors/user/NotificationActor#520978013]
16:57:16.811 [main] INFO o.s.b.w.e.tomcat.TomcatWebServer:108 - Tomcat initialized with port(s): 8081 (http)
16:57:16.820 [main] INFO o.a.coyote.http11.Http11NioProtocol:173 - Initializing ProtocolHandler ["http-nio-8081"]
16:57:16.821 [main] INFO o.a.catalina.core.StandardService:173 - Starting service [Tomcat]
16:57:16.821 [main] INFO o.a.catalina.core.StandardEngine:173 - Starting Servlet engine: [Apache Tomcat/9.0.46]
16:57:16.869 [main] INFO o.a.c.c.C.[Tomcat].[localhost].[/]:173 - Initializing Spring embedded WebApplicationContext
16:57:16.869 [main] INFO o.s.b.w.s.c.ServletWebServerApplicationContext:285 - Root WebApplicationContext: initialization completed in 1950 ms
16:57:16.959 [main] INFO i.o.registry.util.OSResourceLoader:43 - Number of resources loaded 0
16:57:16.959 [main] INFO i.o.registry.util.DefinitionsManager:75 - loaded schema resource(s): 0
16:57:16.971 [main] DEBUG o.h.v.m.ResourceBundleMessageInterpolator:172 - Loaded expression factory via original TCCL
16:57:16.972 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:171 - Setting custom MessageInterpolator of type org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator
16:57:16.974 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:203 - Setting custom ConstraintValidatorFactory of type org.springframework.validation.beanvalidation.SpringConstraintValidatorFactory
16:57:16.976 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:217 - Setting custom ParameterNameProvider of type org.springframework.validation.beanvalidation.LocalValidatorFactoryBean$1
16:57:16.978 [main] DEBUG o.h.v.i.x.config.ValidationXmlParser:120 - Trying to load META-INF/validation.xml for XML based Validator configuration.
16:57:16.978 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:53 - Trying to load META-INF/validation.xml via user class loader
16:57:16.979 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:60 - Trying to load META-INF/validation.xml via TCCL
16:57:16.980 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:66 - Trying to load META-INF/validation.xml via Hibernate Validator's class loader
16:57:16.981 [main] DEBUG o.h.v.i.x.config.ValidationXmlParser:127 - No META-INF/validation.xml found. Using annotation based configuration only.
16:57:16.987 [main] DEBUG o.h.v.i.e.r.TraversableResolvers:60 - Cannot find javax.persistence.Persistence on classpath. Assuming non JPA 2 environment. All properties will per default be traversable.
16:57:16.993 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:300 - HV000252: Using org.hibernate.validator.internal.engine.DefaultPropertyNodeNameProvider as property node name provider.
16:57:16.996 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:375 - HV000234: Using org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator as ValidatorFactory-scoped message interpolator.
16:57:16.996 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:376 - HV000234: Using org.hibernate.validator.internal.engine.resolver.TraverseAllTraversableResolver as ValidatorFactory-scoped traversable resolver.
16:57:16.996 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:377 - HV000234: Using org.hibernate.validator.internal.util.ExecutableParameterNameProvider as ValidatorFactory-scoped parameter name provider.
16:57:16.996 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:378 - HV000234: Using org.hibernate.validator.internal.engine.DefaultClockProvider as ValidatorFactory-scoped clock provider.
16:57:16.997 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:379 - HV000234: Using org.hibernate.validator.internal.engine.scripting.DefaultScriptEvaluatorFactory as ValidatorFactory-scoped script evaluator factory.
16:57:17.272 [main] DEBUG o.k.a.s.f.KeycloakSecurityContextRequestFilter:242 - Filter 'keycloakSecurityContextRequestFilter' configured for use
16:57:17.272 [main] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:242 - Filter 'keycloakAuthenticationProcessingFilter' configured for use
16:57:17.273 [main] DEBUG o.k.a.s.f.KeycloakPreAuthActionsFilter:242 - Filter 'keycloakPreAuthActionsFilter' configured for use
16:57:17.273 [main] DEBUG o.k.a.s.f.KeycloakAuthenticatedActionsFilter:242 - Filter 'keycloakAuthenticatedActionsRequestFilter' configured for use
16:57:17.274 [main] DEBUG o.k.a.t.AbstractKeycloakAuthenticatorValve:116 - Using org.keycloak.adapters.springboot.KeycloakSpringBootConfigResolverWrapper to resolve Keycloak configuration on a per-request basis.
16:57:17.307 [main] DEBUG o.h.v.m.ResourceBundleMessageInterpolator:172 - Loaded expression factory via original TCCL
16:57:17.309 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:171 - Setting custom MessageInterpolator of type org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator
16:57:17.309 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:203 - Setting custom ConstraintValidatorFactory of type org.springframework.validation.beanvalidation.SpringConstraintValidatorFactory
16:57:17.309 [main] DEBUG o.h.v.i.e.AbstractConfigurationImpl:217 - Setting custom ParameterNameProvider of type org.springframework.validation.beanvalidation.LocalValidatorFactoryBean$1
16:57:17.310 [main] DEBUG o.h.v.i.x.config.ValidationXmlParser:120 - Trying to load META-INF/validation.xml for XML based Validator configuration.
16:57:17.311 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:53 - Trying to load META-INF/validation.xml via user class loader
16:57:17.311 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:60 - Trying to load META-INF/validation.xml via TCCL
16:57:17.312 [main] DEBUG o.h.v.i.x.c.ResourceLoaderHelper:66 - Trying to load META-INF/validation.xml via Hibernate Validator's class loader
16:57:17.313 [main] DEBUG o.h.v.i.x.config.ValidationXmlParser:127 - No META-INF/validation.xml found. Using annotation based configuration only.
16:57:17.318 [main] DEBUG o.h.v.i.e.r.TraversableResolvers:60 - Cannot find javax.persistence.Persistence on classpath. Assuming non JPA 2 environment. All properties will per default be traversable.
16:57:17.324 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:300 - HV000252: Using org.hibernate.validator.internal.engine.DefaultPropertyNodeNameProvider as property node name provider.
16:57:17.325 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:375 - HV000234: Using org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator as ValidatorFactory-scoped message interpolator.
16:57:17.325 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:376 - HV000234: Using org.hibernate.validator.internal.engine.resolver.TraverseAllTraversableResolver as ValidatorFactory-scoped traversable resolver.
16:57:17.326 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:377 - HV000234: Using org.hibernate.validator.internal.util.ExecutableParameterNameProvider as ValidatorFactory-scoped parameter name provider.
16:57:17.326 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:378 - HV000234: Using org.hibernate.validator.internal.engine.DefaultClockProvider as ValidatorFactory-scoped clock provider.
16:57:17.326 [main] DEBUG o.h.v.i.e.ValidatorFactoryConfigurationHelper:379 - HV000234: Using org.hibernate.validator.internal.engine.scripting.DefaultScriptEvaluatorFactory as ValidatorFactory-scoped script evaluator factory.
16:57:17.339 [main] INFO i.o.registry.sink.shard.ShardAdvisor:24 - Invoked shard advisor class with classname: io.opensaber.registry.sink.shard.DefaultShardAdvisor
16:57:17.683 [main] INFO i.o.registry.util.ServiceProvider:45 - Invoked search provider class with classname: io.opensaber.registry.service.NativeReadService
16:57:17.690 [main] INFO i.o.r.config.GenericConfiguration:270 - 0 definitions added to validator service
16:57:17.694 [main] INFO i.o.registry.util.ServiceProvider:26 - Invoked search provider class with classname: io.opensaber.registry.service.ElasticSearchService
16:57:17.731 [main] INFO i.o.registry.util.OSResourceLoader:43 - Number of resources loaded 7
16:57:17.784 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:94 - Loading kie.conf from jar:file:/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar!/BOOT-INF/lib/drools-core-7.49.0.Final.jar!/META-INF/kie.conf in classloader org.springframework.boot.loader.LaunchedURLClassLoader@7daf6ecc
16:57:17.786 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.core.io.impl.ResourceFactoryServiceImpl with priority 0
16:57:17.787 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.core.concurrent.ExecutorProviderImpl with priority 0
16:57:17.787 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:94 - Loading kie.conf from jar:file:/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar!/BOOT-INF/lib/kie-internal-7.49.0.Final.jar!/META-INF/kie.conf in classloader org.springframework.boot.loader.LaunchedURLClassLoader@7daf6ecc
16:57:17.789 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.kie.internal.services.KieAssemblersImpl with priority 0
16:57:17.790 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.kie.internal.services.KieRuntimesImpl with priority 0
16:57:17.791 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.kie.internal.services.KieWeaversImpl with priority 0
16:57:17.792 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.kie.internal.services.KieBeliefsImpl with priority 0
16:57:17.792 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:94 - Loading kie.conf from jar:file:/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar!/BOOT-INF/lib/drools-compiler-7.49.0.Final.jar!/META-INF/kie.conf in classloader org.springframework.boot.loader.LaunchedURLClassLoader@7daf6ecc
16:57:17.801 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.compiler.kie.builder.impl.KieServicesImpl with priority 0
16:57:17.803 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.compiler.builder.impl.KnowledgeBuilderFactoryServiceImpl with priority 0
16:57:17.804 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.compiler.builder.impl.JaxbConfigurationFactoryServiceImpl with priority 0
16:57:17.805 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.compiler.kie.builder.impl.KieBaseUpdatersImpl with priority 0
16:57:17.805 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:94 - Loading kie.conf from jar:file:/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar!/BOOT-INF/lib/drools-decisiontables-7.49.0.Final.jar!/META-INF/kie.conf in classloader org.springframework.boot.loader.LaunchedURLClassLoader@7daf6ecc
16:57:17.809 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.decisiontable.DecisionTableProviderImpl with priority 0
16:57:17.809 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:94 - Loading kie.conf from jar:file:/home/gamemaker1/Projects/sunbird-rc/scripts/sunbird-rc-sources/core/java/registry/target/registry.jar!/BOOT-INF/lib/drools-mvel-7.49.0.Final.jar!/META-INF/kie.conf in classloader org.springframework.boot.loader.LaunchedURLClassLoader@7daf6ecc
16:57:17.851 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.mvel.MVELConstraintBuilder with priority 0
16:57:17.852 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.mvel.MVELCoreComponentsBuilder with priority 0
16:57:17.855 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.mvel.asm.ClassFieldAccessorFactory with priority 0
16:57:17.864 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:125 - Added Service org.drools.mvel.asm.DefaultClassBuilderFactory with priority 0
16:57:17.865 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.internal.builder.KnowledgeBuilderFactoryService is implemented by org.drools.compiler.builder.impl.KnowledgeBuilderFactoryServiceImpl@3a4621bd
16:57:17.865 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.internal.runtime.beliefs.KieBeliefs is implemented by org.kie.internal.services.KieBeliefsImpl@31dadd46
16:57:17.865 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.compiler.rule.builder.ConstraintBuilder is implemented by org.drools.mvel.MVELConstraintBuilder@4ed5eb72
16:57:17.865 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.core.base.FieldAccessorFactory is implemented by org.drools.mvel.asm.ClassFieldAccessorFactory@12f9af83
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.compiler.kie.builder.impl.KieBaseUpdaters is implemented by org.drools.compiler.kie.builder.impl.KieBaseUpdatersImpl@19b93fa8
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.internal.assembler.KieAssemblers is implemented by org.kie.internal.services.KieAssemblersImpl@7e6ef134
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.internal.builder.JaxbConfigurationFactoryService is implemented by org.drools.compiler.builder.impl.JaxbConfigurationFactoryServiceImpl@1f010bf0
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.internal.runtime.KieRuntimes is implemented by org.kie.internal.services.KieRuntimesImpl@40db2a24
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.io.KieResources is implemented by org.drools.core.io.impl.ResourceFactoryServiceImpl@10cf09e8
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.core.base.CoreComponentsBuilder is implemented by org.drools.mvel.MVELCoreComponentsBuilder@1921ad94
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.core.factmodel.ClassBuilderFactory is implemented by org.drools.mvel.asm.DefaultClassBuilderFactory@ee86bcb
16:57:17.866 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.drools.compiler.compiler.DecisionTableProvider is implemented by org.drools.decisiontable.DecisionTableProviderImpl@177bea38
16:57:17.867 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.concurrent.KieExecutors is implemented by org.drools.core.concurrent.ExecutorProviderImpl@7f132176
16:57:17.867 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.KieServices is implemented by org.drools.compiler.kie.builder.impl.KieServicesImpl@6bca7e0d
16:57:17.867 [main] DEBUG o.k.a.i.utils.ServiceDiscoveryImpl:149 - Service org.kie.api.internal.weaver.KieWeavers is implemented by org.kie.internal.services.KieWeaversImpl@2631f68c
16:57:18.024 [main] WARN o.d.c.k.builder.impl.KieBuilderImpl:394 - File 'workflow/statetransitions.drl' is in folder 'workflow' but declares package 'io.opensaber.workflow'. It is advised to have a correspondance between package and folder names.
16:57:18.038 [main] DEBUG o.d.c.c.JavaDialectConfiguration:84 - Selected compiler ECLIPSE [drools.dialect.java.compiler:null, hasEclipseCompiler:true]
16:57:18.176 [main] DEBUG o.d.c.c.JavaDialectConfiguration:84 - Selected compiler ECLIPSE [drools.dialect.java.compiler:null, hasEclipseCompiler:true]
16:57:18.176 [main] DEBUG o.d.c.c.JavaDialectConfiguration:84 - Selected compiler ECLIPSE [drools.dialect.java.compiler:null, hasEclipseCompiler:true]
16:57:18.548 [main] DEBUG o.d.c.k.b.impl.KieRepositoryImpl:111 - KieModule was added: MemoryKieModule[releaseId=org.default:artifact:1.0.0]
16:57:18.549 [main] DEBUG o.d.c.k.b.impl.KieRepositoryImpl:89 - Cannot load a KieRepositoryScanner, using the DummyKieScanner
16:57:18.630 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBElementProvider, Method : AbstractJAXBProvider
16:57:18.631 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBElementProvider, Method : AbstractJAXBProvider
16:57:18.634 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlTypeProvider, Method : AbstractJAXBProvider
16:57:18.634 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlTypeProvider, Method : AbstractJAXBProvider
16:57:18.643 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:71 - Provider : org.jboss.resteasy.plugins.providers.jaxb.CollectionProvider, Method : CollectionProvider
16:57:18.643 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:71 - Provider : org.jboss.resteasy.plugins.providers.jaxb.CollectionProvider, Method : CollectionProvider
16:57:18.655 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlRootElementProvider, Method : AbstractJAXBProvider
16:57:18.655 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlRootElementProvider, Method : AbstractJAXBProvider
16:57:18.664 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:56 - Provider : org.jboss.resteasy.plugins.providers.DocumentProvider, Method : DocumentProvider
16:57:18.669 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:66 - RESTEASY002330: Unable to retrieve config: expandEntityReferences defaults to false
16:57:18.669 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:75 - RESTEASY002335: Unable to retrieve config: enableSecureProcessingFeature defaults to true
16:57:18.670 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:84 - RESTEASY002325: Unable to retrieve config: disableDTDs defaults to true
16:57:18.670 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:56 - Provider : org.jboss.resteasy.plugins.providers.DocumentProvider, Method : DocumentProvider
16:57:18.671 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:66 - RESTEASY002330: Unable to retrieve config: expandEntityReferences defaults to false
16:57:18.672 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:75 - RESTEASY002335: Unable to retrieve config: enableSecureProcessingFeature defaults to true
16:57:18.672 [main] DEBUG o.jboss.resteasy.resteasy_jaxrs.i18n:84 - RESTEASY002325: Unable to retrieve config: disableDTDs defaults to true
16:57:18.677 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlSeeAlsoProvider, Method : AbstractJAXBProvider
16:57:18.677 [main] DEBUG o.j.r.plugins.providers.jaxb.i18n:51 - Provider : org.jboss.resteasy.plugins.providers.jaxb.JAXBXmlSeeAlsoProvider, Method : AbstractJAXBProvider
16:57:18.732 [main] DEBUG i.o.registry.frame.FrameContext:46 - for FrameContext registryContextBase: http://localhost:8081/ and frame content: {
"@context":
{
"rdf": "http://www.w3.org/1999/02/22-rdf-syntax-ns#",
"rdfs": "http://www.w3.org/2000/01/rdf-schema#",
"teacher": "http://localhost:8080/",
"xsd": "http://www.w3.org/2001/XMLSchema#",
"sc": "https://w3id.org/security/v1/",
"@vocab": "http://localhost:8080/",
"gender":
{
"@type": "@id"
},
"birthDate":
{
"@type": "xsd:date"
},
"socialCategory":
{
"@type": "@id"
},
"highestAcademicQualification":
{
"@type": "@id"
},
"highestTeacherQualification":
{
"@type": "@id"
},
"yearOfJoiningService":
{
"@type": "xsd:gYear"
},
"teachingRole":
{
"@type": "@id"
},
"teacherType":
{
"@type": "@id"
},
"appointmentType":
{
"@type": "@id"
},
"classesTaught":
{
"@type": "@id"
},
"appointedForSubjects":
{
"@type": "@id"
},
"appointmentYear":
{
"@type": "xsd:gYear"
},
"inServiceTeacherTrainingFromBRC":
{
"@type": "@id"
},
"daysOfInServiceTeacherTraining":
{
"@type": "xsd:decimal"
},
"inServiceTeacherTrainingFromCRC":
{
"@type": "@id"
},
"inServiceTeacherTrainingFromDIET":
{
"@type": "@id"
},
"inServiceTeacherTrainingFromOthers":
{
"@type": "@id"
},
"nonTeachingAssignmentsForAcademicCalendar":
{
"@type": "@id"
},
"daysOfNonTeachingAssignments":
{
"@type": "xsd:decimal"
},
"disabilityType":
{
"@type": "@id"
},
"trainedForChildrenSpecialNeeds":
{
"@type": "@id"
},
"trainedinUseOfComputer":
{
"@type": "@id"
},
"mainSubjectsTaught":
{
"@type": "@id"
},
"basicProficiencyLevel":
{
"@type": "@id"
},
"proficiencySubject":
{
"@type": "@id"
},
"proficiencyAcademicQualification":
{
"@type": "@id"
},
"created":
{
"@type": "sc:created"
},
"creator":
{
"@type": "sc:creator"
},
"nonce":
{
"@type": "sc:nonce"
},
"signatureFor":
{
"@type": "xsd:anyURI"
},
"signatureValue":
{
"@type": "sc:signatureValue"
}
},
"@type": "<@type>"
}
16:57:18.940 [main] INFO o.s.s.c.ThreadPoolTaskExecutor:181 - Initializing ExecutorService
16:57:18.941 [main] INFO o.s.s.c.ThreadPoolTaskExecutor:181 - Initializing ExecutorService 'taskExecutor'
16:57:18.942 [main] INFO o.s.s.c.ThreadPoolTaskExecutor:181 - Initializing ExecutorService
16:57:18.942 [main] INFO o.s.s.c.ThreadPoolTaskExecutor:181 - Initializing ExecutorService 'auditExecutor'
16:57:19.020 [main] INFO o.s.s.web.DefaultSecurityFilterChain:43 - Creating filter chain: any request, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@34625ccd, org.springframework.security.web.context.SecurityContextPersistenceFilter@63192798, org.springframework.security.web.header.HeaderWriterFilter@3f1c5af9, org.keycloak.adapters.springsecurity.filter.KeycloakPreAuthActionsFilter@68dc098b, org.keycloak.adapters.springsecurity.filter.KeycloakAuthenticationProcessingFilter@571c5681, org.springframework.security.web.authentication.logout.LogoutFilter@3ce3db41, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@58e6d4b8, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@28d6290, org.keycloak.adapters.springsecurity.filter.KeycloakSecurityContextRequestFilter@38ba6ce3, org.keycloak.adapters.springsecurity.filter.KeycloakAuthenticatedActionsFilter@d278d2b, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@2c7d121c, org.springframework.security.web.session.SessionManagementFilter@5ddabb18, org.springframework.security.web.access.ExceptionTranslationFilter@292d1c71, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@24bdb479]
16:57:19.411 [main] WARN o.s.b.a.g.t.GroovyTemplateAutoConfiguration:86 - Cannot find template location: classpath:/templates/ (please add some templates, check your Groovy configuration, or set spring.groovy.template.check-template-location=false)
16:57:19.674 [main] INFO o.a.coyote.http11.Http11NioProtocol:173 - Starting ProtocolHandler ["http-nio-8081"]
16:57:19.687 [main] INFO o.s.b.w.e.tomcat.TomcatWebServer:220 - Tomcat started on port(s): 8081 (http) with context path ''
16:57:19.697 [main] INFO o.s.boot.loader.JarLauncher:61 - Started JarLauncher in 5.413 seconds (JVM running for 5.974)
16:57:19.699 [main] INFO i.o.registry.app.AppStartupRunner:22 - On Boot starts loading: parent vertex and shard records
16:57:19.702 [main] INFO i.o.registry.util.EntityParenter:120 - Start - ensure parent node for defined schema
16:57:19.702 [main] INFO i.o.registry.util.EntityParenter:124 - Starting to parents for 0 definitions in shard shard1
16:57:19.975 [MLog-Init-Reporter] INFO com.mchange.v2.log.MLog:212 - MLog clients using slf4j logging.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Reading VM config for path list /com/mchange/v2/log/default-mchange-log.properties, /mchange-commons.properties, /c3p0.properties, hocon:/reference,/application,/c3p0,/, /mchange-log.properties, /
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - The configuration file for resource identifier '/mchange-commons.properties' could not be found. Skipping.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - The configuration file for resource identifier '/c3p0.properties' could not be found. Skipping.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Missing or empty HOCON configuration for resource path 'application'.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Missing or empty HOCON configuration for resource path 'c3p0'.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.enabledProtocols' could not be converted to a String. Skipping.
16:57:19.983 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.enabledCipherSuites' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.keyManager.stores' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.disabledSignatureAlgorithms' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.actor.deployment.default.routees.paths' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.classic.netty.tcp.applied-adapters' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.artery.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting-done.depends-on' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.library-extensions' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.service-requests-done.depends-on' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.loggers' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.classic.enabled-transports' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.classic.netty.ssl.security.enabled-algorithms' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-sharding-shutdown-region.depends-on' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.deployment.whitelist' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting.depends-on' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.disabledKeyAlgorithms' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.trustManager.stores' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.sslParameters.protocols' could not be converted to a String. Skipping.
16:57:19.984 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.before-actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.artery.advanced.instruments' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.extensions' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.service-unbind.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.before-cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.artery.ssl.config-ssl-engine.enabled-algorithms' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.actor.deployment.default.target.nodes' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.artery.large-message-destinations' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.service-stop.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'ssl-config.revocationLists' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.classic.netty.ssl.applied-adapters' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-leave.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.remote.classic.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:19.985 [MLog-Init-Reporter] DEBUG com.mchange.v2.log.MLog:207 - The configuration file for resource identifier '/mchange-log.properties' could not be found. Skipping.
16:57:20.006 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/mchange-commons.properties' could not be found. Skipping.
16:57:20.007 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/mchange-log.properties' could not be found. Skipping.
16:57:20.007 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Missing or empty HOCON configuration for resource path 'application'.
16:57:20.007 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Missing or empty HOCON configuration for resource path 'c3p0'.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.enabledProtocols' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.enabledCipherSuites' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.keyManager.stores' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.disabledSignatureAlgorithms' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.actor.deployment.default.routees.paths' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.tcp.applied-adapters' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting-done.depends-on' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:20.008 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.library-extensions' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-requests-done.depends-on' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.loggers' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.enabled-transports' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.ssl.security.enabled-algorithms' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-sharding-shutdown-region.depends-on' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.deployment.whitelist' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting.depends-on' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.disabledKeyAlgorithms' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.trustManager.stores' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.sslParameters.protocols' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.before-actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.advanced.instruments' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.extensions' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-unbind.depends-on' could not be converted to a String. Skipping.
16:57:20.009 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.before-cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.ssl.config-ssl-engine.enabled-algorithms' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.actor.deployment.default.target.nodes' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.large-message-destinations' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-stop.depends-on' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.revocationLists' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.ssl.applied-adapters' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-leave.depends-on' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:20.010 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/c3p0.properties' could not be found. Skipping.
16:57:20.078 [main] INFO com.mchange.v2.c3p0.C3P0Registry:212 - Initializing c3p0-0.9.5.2 [built 08-December-2015 22:06:04 -0800; debug? true; trace: 10]
16:57:20.096 [main] DEBUG c.m.v.c.m.DynamicPooledDataSourceManagerMBean:204 - MBean: com.mchange.v2.c3p0:type=PooledDataSource,identityToken=2rvy10akhyurqfkpc52z|41c62850,name=2rvy10akhyurqfkpc52z|41c62850 registered.
16:57:20.120 [main] DEBUG c.m.v.c.m.DynamicPooledDataSourceManagerMBean:204 - MBean: com.mchange.v2.c3p0:type=PooledDataSource,identityToken=2rvy10akhyurqfkpc52z|41c62850,name=2rvy10akhyurqfkpc52z|41c62850 unregistered, in order to be reregistered after update.
16:57:20.120 [main] DEBUG c.m.v.c.m.DynamicPooledDataSourceManagerMBean:204 - MBean: com.mchange.v2.c3p0:type=PooledDataSource,identityToken=2rvy10akhyurqfkpc52z|41c62850,name=2rvy10akhyurqfkpc52z|41c62850 registered.
16:57:20.124 [main] DEBUG org.umlg.sqlg.structure.SqlgGraph:299 - Opening graph. Connection url = jdbc:postgresql://localhost:5432/registry, maxPoolSize = 100
16:57:20.156 [main] INFO c.m.v.c.i.AbstractPoolBackedDataSource:212 - Initializing c3p0 pool... com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, contextClassLoaderSource -> caller, dataSourceName -> 2rvy10akhyurqfkpc52z|41c62850, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> org.postgresql.Driver, extensions -> {}, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, forceSynchronousCheckins -> false, forceUseNamedDriverClass -> true, identityToken -> 2rvy10akhyurqfkpc52z|41c62850, idleConnectionTestPeriod -> 0, initialPoolSize -> 3, jdbcUrl -> jdbc:postgresql://localhost:5432/registry, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 3600, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 100, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 3, numHelperThreads -> 3, preferredTestQuery -> null, privilegeSpawnedThreads -> false, properties -> {user=******, password=******}, propertyCycle -> 0, statementCacheNumDeferredCloseThreads -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, userOverrides -> {}, usesTraditionalReflectiveProxies -> false ]
16:57:20.187 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/mchange-commons.properties' could not be found. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/mchange-log.properties' could not be found. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - The configuration file for resource identifier '/c3p0.properties' could not be found. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Missing or empty HOCON configuration for resource path 'application'.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Missing or empty HOCON configuration for resource path 'c3p0'.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.enabledProtocols' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.enabledCipherSuites' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.keyManager.stores' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.disabledSignatureAlgorithms' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.actor.deployment.default.routees.paths' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.tcp.applied-adapters' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting-done.depends-on' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.library-extensions' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-requests-done.depends-on' could not be converted to a String. Skipping.
16:57:20.188 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.loggers' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.enabled-transports' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.ssl.security.enabled-algorithms' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-sharding-shutdown-region.depends-on' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.deployment.whitelist' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-exiting.depends-on' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.disabledKeyAlgorithms' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.trustManager.stores' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.sslParameters.protocols' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.before-actor-system-terminate.depends-on' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.advanced.instruments' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.extensions' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-unbind.depends-on' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.before-cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.ssl.config-ssl-engine.enabled-algorithms' could not be converted to a String. Skipping.
16:57:20.189 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.actor.deployment.default.target.nodes' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.artery.large-message-destinations' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.service-stop.depends-on' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'ssl-config.revocationLists' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.netty.ssl.applied-adapters' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-leave.depends-on' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.remote.classic.trusted-selection-paths' could not be converted to a String. Skipping.
16:57:20.190 [main] DEBUG com.mchange.v2.cfg.MConfig:207 - Value at path 'akka.coordinated-shutdown.phases.cluster-shutdown.depends-on' could not be converted to a String. Skipping.
16:57:20.191 [main] DEBUG c.m.v.resourcepool.BasicResourcePool:204 - com.mchange.v2.resourcepool.BasicResourcePool@5339bbad config: [start -> 3; min -> 3; max -> 100; inc -> 3; num_acq_attempts -> 30; acq_attempt_delay -> 1000; check_idle_resources_delay -> 0; max_resource_age -> 0; max_idle_time -> 3600000; excess_max_idle_time -> 0; destroy_unreturned_resc_time -> 0; expiration_enforcement_delay -> 900000; break_on_acquisition_failure -> false; debug_store_checkout_exceptions -> false; force_synchronous_checkins -> false]
16:57:20.192 [C3P0PooledConnectionPoolManager[identityToken->2rvy10akhyurqfkpc52z|41c62850]-HelperThread-#2] DEBUG c.m.v2.c3p0.DriverManagerDataSource:204 - Circumventing DriverManager and instantiating driver class 'org.postgresql.Driver' directly. (forceUseNamedDriverClass = true)
16:57:20.192 [main] DEBUG c.m.v.c.i.C3P0PooledConnectionPoolManager:204 - Created new pool for auth, username (masked): 'po******'.
16:57:20.192 [main] DEBUG c.m.v.resourcepool.BasicResourcePool:204 - acquire test -- pool size: 0; target_pool_size: 3; desired target? 1
16:57:20.192 [main] DEBUG c.m.v.resourcepool.BasicResourcePool:204 - awaitAvailable(): [unknown]
16:57:20.381 [main] DEBUG c.m.v2.c3p0.impl.NewProxyConnection:204 - com.mchange.v2.c3p0.impl.NewProxyConnection@64e92d61 [wrapping: null]: close() called more than once.
16:57:20.383 [main] DEBUG o.u.s.structure.SqlgStartupManager:51 - SchemaManager.loadSqlgSchema()...
16:57:20.386 [main] DEBUG c.m.v2.c3p0.impl.NewProxyConnection:204 - com.mchange.v2.c3p0.impl.NewProxyConnection@1b812421 [wrapping: null]: close() called more than once.
16:57:20.389 [main] DEBUG o.u.s.structure.SqlgStartupManager:75 - Time to createVertexLabel sqlg topology: 00:00:00.001
16:57:20.461 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_graph"."ID" AS "alias1",
"sqlg_schema"."V_graph"."dbVersion" AS "alias2",
"sqlg_schema"."V_graph"."updatedOn" AS "alias3",
"sqlg_schema"."V_graph"."version" AS "alias4",
"sqlg_schema"."V_graph"."createdOn" AS "alias5"
FROM
"sqlg_schema"."V_graph"
16:57:20.494 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_graph"."ID" AS "alias1",
"sqlg_schema"."V_graph"."dbVersion" AS "alias2",
"sqlg_schema"."V_graph"."updatedOn" AS "alias3",
"sqlg_schema"."V_graph"."version" AS "alias4",
"sqlg_schema"."V_graph"."createdOn" AS "alias5"
FROM
"sqlg_schema"."V_graph"
16:57:20.504 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_log"."ID" AS "alias1",
"sqlg_schema"."V_log"."log" AS "alias2",
"sqlg_schema"."V_log"."pid" AS "alias3",
"sqlg_schema"."V_log"."timestamp" AS "alias4"
FROM
"sqlg_schema"."V_log"
ORDER BY
"alias4" DESC
LIMIT 1 OFFSET 0
16:57:20.506 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_schema"."ID" AS "alias1",
"sqlg_schema"."V_schema"."name" AS "alias2",
"sqlg_schema"."V_schema"."createdOn" AS "alias3"
FROM
"sqlg_schema"."V_schema"
16:57:20.536 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias1",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias2",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias3",
"sqlg_schema"."V_vertex"."name" AS "alias4",
"sqlg_schema"."V_vertex"."createdOn" AS "alias5",
"sqlg_schema"."V_vertex"."partitionType" AS "alias6",
"sqlg_schema"."V_vertex"."shardCount" AS "alias7",
"sqlg_schema"."V_schema"."ID" AS "alias8",
"sqlg_schema"."V_schema"."name" AS "alias9",
"sqlg_schema"."V_schema"."createdOn" AS "alias10"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
16:57:20.541 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
1 as "index",
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."E_vertex_property"."ID" AS "alias5",
"sqlg_schema"."E_vertex_property"."sqlg_schema.vertex__O" AS "alias6",
"sqlg_schema"."E_vertex_property"."sqlg_schema.property__I" AS "alias7"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_vertex_property" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_property"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_vertex_property"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
ORDER BY
"index"
16:57:20.544 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
1 as "index",
"sqlg_schema"."V_partition"."ID" AS "alias1",
"sqlg_schema"."V_partition"."partitionExpression" AS "alias2",
"sqlg_schema"."V_partition"."in" AS "alias3",
"sqlg_schema"."V_partition"."name" AS "alias4",
"sqlg_schema"."V_partition"."from" AS "alias5",
"sqlg_schema"."V_partition"."to" AS "alias6",
"sqlg_schema"."V_partition"."createdOn" AS "alias7",
"sqlg_schema"."V_partition"."partitionType" AS "alias8",
"sqlg_schema"."E_vertex_partition"."ID" AS "alias9",
"sqlg_schema"."E_vertex_partition"."sqlg_schema.partition__I" AS "alias10",
"sqlg_schema"."E_vertex_partition"."sqlg_schema.vertex__O" AS "alias11"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_vertex_partition" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_partition"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_partition" ON "sqlg_schema"."E_vertex_partition"."sqlg_schema.partition__I" = "sqlg_schema"."V_partition"."ID"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
ORDER BY
"index"
16:57:20.545 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
1 as "index",
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."E_vertex_identifier"."ID" AS "alias5",
"sqlg_schema"."E_vertex_identifier"."identifier_index" AS "alias6",
"sqlg_schema"."E_vertex_identifier"."sqlg_schema.vertex__O" AS "alias7",
"sqlg_schema"."E_vertex_identifier"."sqlg_schema.property__I" AS "alias8"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_vertex_identifier" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_identifier"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_vertex_identifier"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
ORDER BY
"index"
16:57:20.546 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 - SELECT
a1."index" as "index",
a1."alias1", a1."alias2", a1."alias3", a2."alias4", a2."alias5", a2."alias6", a2."alias7", a2."alias8", a2."alias9", a2."alias10"
FROM (
SELECT
1 as "index",
"sqlg_schema"."E_vertex_colocate"."sqlg_schema.vertex__I" AS "sqlg_schema.E_vertex_colocate.sqlg_schema.vertex__I",
"sqlg_schema"."E_vertex_colocate"."ID" AS "alias1",
"sqlg_schema"."E_vertex_colocate"."sqlg_schema.vertex__I" AS "alias2",
"sqlg_schema"."E_vertex_colocate"."sqlg_schema.vertex__O" AS "alias3"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_vertex_colocate" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_colocate"."sqlg_schema.vertex__O"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
) a1 INNER JOIN (
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias4",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias5",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias6",
"sqlg_schema"."V_vertex"."name" AS "alias7",
"sqlg_schema"."V_vertex"."createdOn" AS "alias8",
"sqlg_schema"."V_vertex"."partitionType" AS "alias9",
"sqlg_schema"."V_vertex"."shardCount" AS "alias10"
FROM
"sqlg_schema"."V_vertex"
) a2 ON a1."sqlg_schema.E_vertex_colocate.sqlg_schema.vertex__I" = a2."alias4"
16:57:20.547 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
1 as "index",
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."E_vertex_distribution"."ID" AS "alias5",
"sqlg_schema"."E_vertex_distribution"."sqlg_schema.vertex__O" AS "alias6",
"sqlg_schema"."E_vertex_distribution"."sqlg_schema.property__I" AS "alias7"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_vertex_distribution" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_distribution"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_vertex_distribution"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
ORDER BY
"index"
16:57:20.557 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias1",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias2",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias3",
"sqlg_schema"."V_vertex"."name" AS "alias4",
"sqlg_schema"."V_vertex"."createdOn" AS "alias5",
"sqlg_schema"."V_vertex"."partitionType" AS "alias6",
"sqlg_schema"."V_vertex"."shardCount" AS "alias7",
"sqlg_schema"."V_schema"."ID" AS "alias8",
"sqlg_schema"."V_schema"."name" AS "alias9",
"sqlg_schema"."V_schema"."createdOn" AS "alias10"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
16:57:20.559 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
1 as "index",
"sqlg_schema"."V_edge"."ID" AS "alias1",
"sqlg_schema"."V_edge"."partitionExpression" AS "alias2",
"sqlg_schema"."V_edge"."name" AS "alias3",
"sqlg_schema"."V_edge"."createdOn" AS "alias4",
"sqlg_schema"."V_edge"."partitionType" AS "alias5",
"sqlg_schema"."V_edge"."shardCount" AS "alias6"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_out_edges" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_edge" ON "sqlg_schema"."E_out_edges"."sqlg_schema.edge__I" = "sqlg_schema"."V_edge"."ID"
WHERE
"sqlg_schema"."V_vertex"."ID" = 1
ORDER BY
"index"
16:57:20.561 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."V_schema"."ID" AS "alias5",
"sqlg_schema"."V_schema"."name" AS "alias6",
"sqlg_schema"."V_schema"."createdOn" AS "alias7",
"sqlg_schema"."V_vertex"."ID" AS "alias8",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias9",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias10",
"sqlg_schema"."V_vertex"."name" AS "alias11",
"sqlg_schema"."V_vertex"."createdOn" AS "alias12",
"sqlg_schema"."V_vertex"."partitionType" AS "alias13",
"sqlg_schema"."V_vertex"."shardCount" AS "alias14",
"sqlg_schema"."V_index"."ID" AS "alias15",
"sqlg_schema"."V_index"."name" AS "alias16",
"sqlg_schema"."V_index"."createdOn" AS "alias17",
"sqlg_schema"."V_index"."index_type" AS "alias18",
"sqlg_schema"."E_index_property"."ID" AS "alias19",
"sqlg_schema"."E_index_property"."sequence" AS "alias20",
"sqlg_schema"."E_index_property"."sqlg_schema.property__I" AS "alias21",
"sqlg_schema"."E_index_property"."sqlg_schema.index__O" AS "alias22"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" INNER JOIN
"sqlg_schema"."E_vertex_index" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_index"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_index" ON "sqlg_schema"."E_vertex_index"."sqlg_schema.index__I" = "sqlg_schema"."V_index"."ID" INNER JOIN
"sqlg_schema"."E_index_property" ON "sqlg_schema"."V_index"."ID" = "sqlg_schema"."E_index_property"."sqlg_schema.index__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_index_property"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
ORDER BY
"alias20" ASC
16:57:20.564 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."V_schema"."ID" AS "alias5",
"sqlg_schema"."V_schema"."name" AS "alias6",
"sqlg_schema"."V_schema"."createdOn" AS "alias7",
"sqlg_schema"."V_vertex"."ID" AS "alias8",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias9",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias10",
"sqlg_schema"."V_vertex"."name" AS "alias11",
"sqlg_schema"."V_vertex"."createdOn" AS "alias12",
"sqlg_schema"."V_vertex"."partitionType" AS "alias13",
"sqlg_schema"."V_vertex"."shardCount" AS "alias14",
"sqlg_schema"."V_edge"."ID" AS "alias15",
"sqlg_schema"."V_edge"."partitionExpression" AS "alias16",
"sqlg_schema"."V_edge"."name" AS "alias17",
"sqlg_schema"."V_edge"."createdOn" AS "alias18",
"sqlg_schema"."V_edge"."partitionType" AS "alias19",
"sqlg_schema"."V_edge"."shardCount" AS "alias20",
"sqlg_schema"."V_index"."ID" AS "alias21",
"sqlg_schema"."V_index"."name" AS "alias22",
"sqlg_schema"."V_index"."createdOn" AS "alias23",
"sqlg_schema"."V_index"."index_type" AS "alias24",
"sqlg_schema"."E_index_property"."ID" AS "alias25",
"sqlg_schema"."E_index_property"."sequence" AS "alias26",
"sqlg_schema"."E_index_property"."sqlg_schema.property__I" AS "alias27",
"sqlg_schema"."E_index_property"."sqlg_schema.index__O" AS "alias28"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" INNER JOIN
"sqlg_schema"."E_out_edges" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_edge" ON "sqlg_schema"."E_out_edges"."sqlg_schema.edge__I" = "sqlg_schema"."V_edge"."ID" INNER JOIN
"sqlg_schema"."E_edge_index" ON "sqlg_schema"."V_edge"."ID" = "sqlg_schema"."E_edge_index"."sqlg_schema.edge__O" INNER JOIN
"sqlg_schema"."V_index" ON "sqlg_schema"."E_edge_index"."sqlg_schema.index__I" = "sqlg_schema"."V_index"."ID" INNER JOIN
"sqlg_schema"."E_index_property" ON "sqlg_schema"."V_index"."ID" = "sqlg_schema"."E_index_property"."sqlg_schema.index__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_index_property"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
ORDER BY
"alias26" ASC
16:57:20.568 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias1",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias2",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias3",
"sqlg_schema"."V_vertex"."name" AS "alias4",
"sqlg_schema"."V_vertex"."createdOn" AS "alias5",
"sqlg_schema"."V_vertex"."partitionType" AS "alias6",
"sqlg_schema"."V_vertex"."shardCount" AS "alias7",
"sqlg_schema"."V_schema"."ID" AS "alias8",
"sqlg_schema"."V_schema"."name" AS "alias9",
"sqlg_schema"."V_schema"."createdOn" AS "alias10"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
16:57:20.570 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias1",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias2",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias3",
"sqlg_schema"."V_vertex"."name" AS "alias4",
"sqlg_schema"."V_vertex"."createdOn" AS "alias5",
"sqlg_schema"."V_vertex"."partitionType" AS "alias6",
"sqlg_schema"."V_vertex"."shardCount" AS "alias7",
"sqlg_schema"."V_schema"."ID" AS "alias8",
"sqlg_schema"."V_schema"."name" AS "alias9",
"sqlg_schema"."V_schema"."createdOn" AS "alias10"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
16:57:20.571 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."V_schema"."ID" AS "alias5",
"sqlg_schema"."V_schema"."name" AS "alias6",
"sqlg_schema"."V_schema"."createdOn" AS "alias7",
"sqlg_schema"."V_vertex"."ID" AS "alias8",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias9",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias10",
"sqlg_schema"."V_vertex"."name" AS "alias11",
"sqlg_schema"."V_vertex"."createdOn" AS "alias12",
"sqlg_schema"."V_vertex"."partitionType" AS "alias13",
"sqlg_schema"."V_vertex"."shardCount" AS "alias14",
"sqlg_schema"."V_index"."ID" AS "alias15",
"sqlg_schema"."V_index"."name" AS "alias16",
"sqlg_schema"."V_index"."createdOn" AS "alias17",
"sqlg_schema"."V_index"."index_type" AS "alias18",
"sqlg_schema"."E_index_property"."ID" AS "alias19",
"sqlg_schema"."E_index_property"."sequence" AS "alias20",
"sqlg_schema"."E_index_property"."sqlg_schema.property__I" AS "alias21",
"sqlg_schema"."E_index_property"."sqlg_schema.index__O" AS "alias22"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" INNER JOIN
"sqlg_schema"."E_vertex_index" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_vertex_index"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_index" ON "sqlg_schema"."E_vertex_index"."sqlg_schema.index__I" = "sqlg_schema"."V_index"."ID" INNER JOIN
"sqlg_schema"."E_index_property" ON "sqlg_schema"."V_index"."ID" = "sqlg_schema"."E_index_property"."sqlg_schema.index__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_index_property"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
ORDER BY
"alias20" ASC
16:57:20.575 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_property"."ID" AS "alias1",
"sqlg_schema"."V_property"."name" AS "alias2",
"sqlg_schema"."V_property"."type" AS "alias3",
"sqlg_schema"."V_property"."createdOn" AS "alias4",
"sqlg_schema"."V_schema"."ID" AS "alias5",
"sqlg_schema"."V_schema"."name" AS "alias6",
"sqlg_schema"."V_schema"."createdOn" AS "alias7",
"sqlg_schema"."V_vertex"."ID" AS "alias8",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias9",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias10",
"sqlg_schema"."V_vertex"."name" AS "alias11",
"sqlg_schema"."V_vertex"."createdOn" AS "alias12",
"sqlg_schema"."V_vertex"."partitionType" AS "alias13",
"sqlg_schema"."V_vertex"."shardCount" AS "alias14",
"sqlg_schema"."V_edge"."ID" AS "alias15",
"sqlg_schema"."V_edge"."partitionExpression" AS "alias16",
"sqlg_schema"."V_edge"."name" AS "alias17",
"sqlg_schema"."V_edge"."createdOn" AS "alias18",
"sqlg_schema"."V_edge"."partitionType" AS "alias19",
"sqlg_schema"."V_edge"."shardCount" AS "alias20",
"sqlg_schema"."V_index"."ID" AS "alias21",
"sqlg_schema"."V_index"."name" AS "alias22",
"sqlg_schema"."V_index"."createdOn" AS "alias23",
"sqlg_schema"."V_index"."index_type" AS "alias24",
"sqlg_schema"."E_index_property"."ID" AS "alias25",
"sqlg_schema"."E_index_property"."sequence" AS "alias26",
"sqlg_schema"."E_index_property"."sqlg_schema.property__I" AS "alias27",
"sqlg_schema"."E_index_property"."sqlg_schema.index__O" AS "alias28"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" INNER JOIN
"sqlg_schema"."E_out_edges" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_edge" ON "sqlg_schema"."E_out_edges"."sqlg_schema.edge__I" = "sqlg_schema"."V_edge"."ID" INNER JOIN
"sqlg_schema"."E_edge_index" ON "sqlg_schema"."V_edge"."ID" = "sqlg_schema"."E_edge_index"."sqlg_schema.edge__O" INNER JOIN
"sqlg_schema"."V_index" ON "sqlg_schema"."E_edge_index"."sqlg_schema.index__I" = "sqlg_schema"."V_index"."ID" INNER JOIN
"sqlg_schema"."E_index_property" ON "sqlg_schema"."V_index"."ID" = "sqlg_schema"."E_index_property"."sqlg_schema.index__O" INNER JOIN
"sqlg_schema"."V_property" ON "sqlg_schema"."E_index_property"."sqlg_schema.property__I" = "sqlg_schema"."V_property"."ID"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
ORDER BY
"alias26" ASC
16:57:20.578 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_schema"."ID" AS "alias1",
"sqlg_schema"."V_schema"."name" AS "alias2",
"sqlg_schema"."V_schema"."createdOn" AS "alias3"
FROM
"sqlg_schema"."V_schema"
16:57:20.580 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 - SELECT
a1."alias1", a1."alias2", a1."alias3", a1."alias4", a1."alias5", a1."alias6", a1."alias7", a1."alias8", a1."alias9", a1."alias10", a1."alias11", a1."alias12", a1."alias13", a1."alias14", a1."alias15", a1."alias16", a2."alias17", a2."alias18", a2."alias19", a2."alias20", a2."alias21", a2."alias22", a2."alias23", a2."alias24", a2."alias25", a2."alias26"
FROM (
SELECT
"sqlg_schema"."E_in_edges"."sqlg_schema.vertex__O" AS "sqlg_schema.E_in_edges.sqlg_schema.vertex__O",
"sqlg_schema"."V_schema"."ID" AS "alias1",
"sqlg_schema"."V_schema"."name" AS "alias2",
"sqlg_schema"."V_schema"."createdOn" AS "alias3",
"sqlg_schema"."V_vertex"."ID" AS "alias4",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias5",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias6",
"sqlg_schema"."V_vertex"."name" AS "alias7",
"sqlg_schema"."V_vertex"."createdOn" AS "alias8",
"sqlg_schema"."V_vertex"."partitionType" AS "alias9",
"sqlg_schema"."V_vertex"."shardCount" AS "alias10",
"sqlg_schema"."V_edge"."ID" AS "alias11",
"sqlg_schema"."V_edge"."partitionExpression" AS "alias12",
"sqlg_schema"."V_edge"."name" AS "alias13",
"sqlg_schema"."V_edge"."createdOn" AS "alias14",
"sqlg_schema"."V_edge"."partitionType" AS "alias15",
"sqlg_schema"."V_edge"."shardCount" AS "alias16"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" INNER JOIN
"sqlg_schema"."E_out_edges" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O" INNER JOIN
"sqlg_schema"."V_edge" ON "sqlg_schema"."E_out_edges"."sqlg_schema.edge__I" = "sqlg_schema"."V_edge"."ID" INNER JOIN
"sqlg_schema"."E_in_edges" ON "sqlg_schema"."V_edge"."ID" = "sqlg_schema"."E_in_edges"."sqlg_schema.edge__I"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?)
) a1 INNER JOIN (
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias17",
"sqlg_schema"."V_schema"."ID" AS "alias18",
"sqlg_schema"."V_schema"."name" AS "alias19",
"sqlg_schema"."V_schema"."createdOn" AS "alias20",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias21",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias22",
"sqlg_schema"."V_vertex"."name" AS "alias23",
"sqlg_schema"."V_vertex"."createdOn" AS "alias24",
"sqlg_schema"."V_vertex"."partitionType" AS "alias25",
"sqlg_schema"."V_vertex"."shardCount" AS "alias26"
FROM
"sqlg_schema"."V_vertex" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" INNER JOIN
"sqlg_schema"."V_schema" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" = "sqlg_schema"."V_schema"."ID"
) a2 ON a1."sqlg_schema.E_in_edges.sqlg_schema.vertex__O" = a2."alias17"
16:57:20.582 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_vertex"."ID" AS "alias1",
"sqlg_schema"."V_vertex"."partitionExpression" AS "alias2",
"sqlg_schema"."V_vertex"."schemaVertex" AS "alias3",
"sqlg_schema"."V_vertex"."name" AS "alias4",
"sqlg_schema"."V_vertex"."createdOn" AS "alias5",
"sqlg_schema"."V_vertex"."partitionType" AS "alias6",
"sqlg_schema"."V_vertex"."shardCount" AS "alias7",
"sqlg_schema"."V_schema"."ID" AS "alias8",
"sqlg_schema"."V_schema"."name" AS "alias9",
"sqlg_schema"."V_schema"."createdOn" AS "alias10"
FROM
"sqlg_schema"."V_schema" INNER JOIN
"sqlg_schema"."E_schema_vertex" ON "sqlg_schema"."V_schema"."ID" = "sqlg_schema"."E_schema_vertex"."sqlg_schema.schema__O" INNER JOIN
"sqlg_schema"."V_vertex" ON "sqlg_schema"."E_schema_vertex"."sqlg_schema.vertex__I" = "sqlg_schema"."V_vertex"."ID" LEFT JOIN
"sqlg_schema"."E_out_edges" ON "sqlg_schema"."V_vertex"."ID" = "sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O"
WHERE
( "sqlg_schema"."V_schema"."ID" = ?) AND
("sqlg_schema"."E_out_edges"."sqlg_schema.vertex__O" IS NULL)
16:57:20.583 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"sqlg_schema"."V_globalUniqueIndex"."ID" AS "alias1",
"sqlg_schema"."V_globalUniqueIndex"."name" AS "alias2",
"sqlg_schema"."V_globalUniqueIndex"."createdOn" AS "alias3"
FROM
"sqlg_schema"."V_globalUniqueIndex"
16:57:20.584 [main] DEBUG c.m.v2.c3p0.impl.NewProxyConnection:204 - com.mchange.v2.c3p0.impl.NewProxyConnection@5c089b2f [wrapping: null]: close() called more than once.
16:57:20.584 [main] DEBUG o.umlg.sqlg.strategy.SqlgSqlExecutor:107 -
SELECT
"public"."V_graph_global_config"."ID" AS "alias1",
"public"."V_graph_global_config"."persisten_graph" AS "alias2"
FROM
"public"."V_graph_global_config"
16:57:20.586 [main] DEBUG c.m.v2.c3p0.impl.NewProxyConnection:204 - com.mchange.v2.c3p0.impl.NewProxyConnection@6fe46b62 [wrapping: null]: close() called more than once.
16:57:20.587 [main] INFO i.o.registry.util.EntityParenter:153 - Ensured parents for 0 definitions in shard shard1
16:57:20.587 [main] INFO i.o.registry.util.EntityParenter:161 - End - ensure parent node for defined schema
16:58:30.971 [http-nio-8081-exec-1] DEBUG o.k.adapters.PreAuthActionsHandler:74 - adminRequest http://localhost:8081/sso/login?state=3fc578f0-7910-4f1e-bf41-85eb3d23bd89&session_state=e3981283-d290-429b-b236-41edd943f0aa&code=1f488c26-afc8-4a41-850c-50bd709717ec.e3981283-d290-429b-b236-41edd943f0aa.c8069a2e-9004-4a85-8dbc-2751984d3fc5
16:58:30.975 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:59 - Using provider 'secret' for authentication of client 'sunbird'
16:58:30.976 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider secret
16:58:30.977 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider jwt
16:58:30.977 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider secret-jwt
16:58:30.978 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider secret
16:58:30.978 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider jwt
16:58:30.978 [http-nio-8081-exec-1] DEBUG o.k.a.a.ClientCredentialsProviderUtils:81 - Loaded clientCredentialsProvider secret-jwt
16:58:30.987 [http-nio-8081-exec-1] DEBUG o.k.a.t.AbstractAuthenticatedActionsValve:57 - AuthenticatedActionsValve.invoke /sso/login
16:58:30.987 [http-nio-8081-exec-1] DEBUG o.k.a.AuthenticatedActionsHandler:53 - AuthenticatedActionsValve.invoke http://localhost:8081/sso/login?state=3fc578f0-7910-4f1e-bf41-85eb3d23bd89&session_state=e3981283-d290-429b-b236-41edd943f0aa&code=1f488c26-afc8-4a41-850c-50bd709717ec.e3981283-d290-429b-b236-41edd943f0aa.c8069a2e-9004-4a85-8dbc-2751984d3fc5
16:58:30.988 [http-nio-8081-exec-1] DEBUG o.k.a.AuthenticatedActionsHandler:153 - Policy enforcement is disabled.
16:58:30.989 [http-nio-8081-exec-1] INFO o.a.c.c.C.[Tomcat].[localhost].[/]:173 - Initializing Spring DispatcherServlet 'dispatcherServlet'
16:58:30.989 [http-nio-8081-exec-1] INFO o.s.web.servlet.DispatcherServlet:525 - Initializing Servlet 'dispatcherServlet'
16:58:30.996 [http-nio-8081-exec-1] INFO o.s.web.servlet.DispatcherServlet:547 - Completed initialization in 7 ms
16:58:31.013 [http-nio-8081-exec-1] DEBUG o.k.adapters.PreAuthActionsHandler:74 - adminRequest http://localhost:8081/sso/login?state=3fc578f0-7910-4f1e-bf41-85eb3d23bd89&session_state=e3981283-d290-429b-b236-41edd943f0aa&code=1f488c26-afc8-4a41-850c-50bd709717ec.e3981283-d290-429b-b236-41edd943f0aa.c8069a2e-9004-4a85-8dbc-2751984d3fc5
16:58:31.013 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:206 - Request is to process authentication
16:58:31.014 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:142 - Attempting Keycloak authentication
16:58:31.017 [http-nio-8081-exec-1] DEBUG o.k.a.s.t.SpringSecurityTokenStore:64 - Checking if org.keycloak.adapters.springsecurity.authentication.SpringSecurityRequestAuthenticator@95b2a4a is cached
16:58:31.018 [http-nio-8081-exec-1] DEBUG o.k.a.OAuthRequestAuthenticator:279 - there was a code, resolving
16:58:31.019 [http-nio-8081-exec-1] DEBUG o.k.a.OAuthRequestAuthenticator:325 - checking state cookie for after code
16:58:31.020 [http-nio-8081-exec-1] DEBUG o.k.a.OAuthRequestAuthenticator:244 - ** reseting application state cookie
16:58:31.033 [http-nio-8081-exec-1] DEBUG o.k.adapters.KeycloakDeployment:176 - Resolving URLs from http://localhost:8080/auth/realms/sunbird/.well-known/openid-configuration
16:58:31.071 [http-nio-8081-exec-1] DEBUG o.a.h.i.c.t.ThreadSafeClientConnManager:240 - Get connection: {}->http://localhost:8080, timeout = 0
16:58:31.072 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:347 - [{}->http://localhost:8080] total kept alive: 0, total issued: 0, total allocated: 0 out of 20
16:58:31.072 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:522 - No free connections [{}->http://localhost:8080][null]
16:58:31.072 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:366 - Available capacity: 20 out of 20 [{}->http://localhost:8080][null]
16:58:31.072 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:548 - Creating new connection [{}->http://localhost:8080]
16:58:31.082 [http-nio-8081-exec-1] DEBUG o.a.h.i.c.DefaultClientConnectionOperator:177 - Connecting to localhost:8080
16:58:31.097 [http-nio-8081-exec-1] DEBUG o.a.h.c.protocol.RequestAddCookies:123 - CookieSpec selected: compatibility
16:58:31.098 [http-nio-8081-exec-1] DEBUG o.a.h.c.protocol.RequestAuthCache:77 - Auth cache not set in the context
16:58:31.098 [http-nio-8081-exec-1] DEBUG o.a.h.c.p.RequestTargetAuthentication:80 - Target auth state: UNCHALLENGED
16:58:31.099 [http-nio-8081-exec-1] DEBUG o.a.h.c.p.RequestProxyAuthentication:89 - Proxy auth state: UNCHALLENGED
16:58:31.099 [http-nio-8081-exec-1] DEBUG o.a.h.impl.client.DefaultHttpClient:677 - Attempt 1 to execute request
16:58:31.099 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.DefaultClientConnection:274 - Sending request: GET /auth/realms/sunbird/.well-known/openid-configuration HTTP/1.1
16:58:31.099 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "GET /auth/realms/sunbird/.well-known/openid-configuration HTTP/1.1[\r][\n]"
16:58:31.100 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "accept: application/json[\r][\n]"
16:58:31.100 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Host: localhost:8080[\r][\n]"
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Connection: Keep-Alive[\r][\n]"
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "[\r][\n]"
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:278 - >> GET /auth/realms/sunbird/.well-known/openid-configuration HTTP/1.1
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> accept: application/json
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Host: localhost:8080
16:58:31.101 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Connection: Keep-Alive
16:58:31.106 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "HTTP/1.1 200 OK[\r][\n]"
16:58:31.110 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Cache-Control: no-cache, must-revalidate, no-transform, no-store[\r][\n]"
16:58:31.111 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-XSS-Protection: 1; mode=block[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-Frame-Options: SAMEORIGIN[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Referrer-Policy: no-referrer[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Date: Sat, 02 Oct 2021 11:28:31 GMT[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Connection: keep-alive[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Strict-Transport-Security: max-age=31536000; includeSubDomains[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-Content-Type-Options: nosniff[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Content-Type: application/json[\r][\n]"
16:58:31.112 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Content-Length: 2600[\r][\n]"
16:58:31.113 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "[\r][\n]"
16:58:31.113 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.DefaultClientConnection:259 - Receiving response: HTTP/1.1 200 OK
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:262 - << HTTP/1.1 200 OK
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Cache-Control: no-cache, must-revalidate, no-transform, no-store
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-XSS-Protection: 1; mode=block
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-Frame-Options: SAMEORIGIN
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Referrer-Policy: no-referrer
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Date: Sat, 02 Oct 2021 11:28:31 GMT
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Connection: keep-alive
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Strict-Transport-Security: max-age=31536000; includeSubDomains
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-Content-Type-Options: nosniff
16:58:31.114 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Content-Type: application/json
16:58:31.115 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Content-Length: 2600
16:58:31.117 [http-nio-8081-exec-1] DEBUG o.a.h.impl.client.DefaultHttpClient:504 - Connection can be kept alive indefinitely
16:58:31.130 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:87 - << "{"issuer":"http://localhost:8080/auth/realms/sunbird","authorization_endpoint":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/auth","token_endpoint":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/token","introspection_endpoint":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/token/introspect","userinfo_endpoint":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/userinfo","end_session_endpoint":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/logout","jwks_uri":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/certs","check_session_iframe":"http://localhost:8080/auth/realms/sunbird/protocol/openid-connect/login-status-iframe.html","grant_types_supported":["authorization_code","implicit","refresh_token","password","client_credentials"],"response_types_supported":["code","none","id_token","token","id_token token","code id_token","code token","code id_token token"],"subject_types_supported":["public","pairwise"],"id_token_signing_alg_values_supported":["PS384","ES384","RS384","HS256","HS512","ES256","RS256","HS384","ES512","PS256","PS512","RS512"],"id_token_encryption_alg_values_supported":["RSA-OAEP","RSA1_5"],"id_token_encryption_enc_values_supported":["A256GCM","A192GCM","A128GCM","A128CBC-HS256","A192CBC-HS384","A256CBC-HS512"],"userinfo_signing_alg_values_supported":["PS384","ES384","RS384","HS256","HS512","ES256","RS256","HS384","ES512","PS256","PS512","RS512","none"],"request_object_signing_alg_values_supported":["PS384","ES384","RS384","HS256","HS512","ES256","RS256","HS384","ES512","PS256","PS512","RS512","none"],"response_modes_supported":["query","fragment","form_post"],"registration_endpoint":"http://localhost:8080/auth/realms/sunbird/clients-registrations/openid-connect","token_endpoint_auth_methods_supported":["private_key_jwt","client_secret_basic","client_secret_post","tls_client_auth","client_secret_jwt"],"token_endpoint_auth_signing_alg_values_supported":["PS384","ES384","RS384","HS256","HS512","ES256","RS256","HS384","ES512","PS256","PS512","RS512"],"claims_supported":["aud","sub","iss","auth_time","name","given_name","family_name","preferred_username","email","acr"],"claim_types_supported":["normal"],"claims_parameter_supported":false,"scopes_supported":["openid","offline_access","profile","email","address","phone","roles","web-origins","microprofile-jwt"],"request_parameter_supported":true,"request_uri_parameter_supported":true,"code_challenge_methods_supported":["plain","S256"],"tls_client_certificate_bound_access_tokens":true}"
16:58:31.147 [http-nio-8081-exec-1] DEBUG o.a.h.i.c.t.ThreadSafeClientConnManager:286 - Released connection is reusable.
16:58:31.147 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:431 - Releasing connection [{}->http://localhost:8080][null]
16:58:31.147 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:457 - Pooling connection [{}->http://localhost:8080][null]; keep alive indefinitely
16:58:31.147 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:678 - Notifying no-one, there are no waiting threads
16:58:31.148 [http-nio-8081-exec-1] INFO o.k.adapters.KeycloakDeployment:190 - Loaded URLs from http://localhost:8080/auth/realms/sunbird/.well-known/openid-configuration
16:58:31.148 [http-nio-8081-exec-1] WARN o.k.a.a.ClientIdAndSecretCredentialsProvider:60 - Client 'sunbird' doesn't have secret available
16:58:31.150 [http-nio-8081-exec-1] DEBUG o.a.h.i.c.t.ThreadSafeClientConnManager:240 - Get connection: {}->http://localhost:8080, timeout = 0
16:58:31.151 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:347 - [{}->http://localhost:8080] total kept alive: 1, total issued: 0, total allocated: 1 out of 20
16:58:31.151 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:496 - Getting free connection [{}->http://localhost:8080][null]
16:58:31.151 [http-nio-8081-exec-1] DEBUG o.a.h.impl.client.DefaultHttpClient:426 - Stale connection check
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.c.protocol.RequestAddCookies:123 - CookieSpec selected: compatibility
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.c.protocol.RequestAuthCache:77 - Auth cache not set in the context
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.c.p.RequestTargetAuthentication:80 - Target auth state: UNCHALLENGED
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.c.p.RequestProxyAuthentication:89 - Proxy auth state: UNCHALLENGED
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.impl.client.DefaultHttpClient:677 - Attempt 1 to execute request
16:58:31.153 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.DefaultClientConnection:274 - Sending request: POST /auth/realms/sunbird/protocol/openid-connect/token HTTP/1.1
16:58:31.153 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "POST /auth/realms/sunbird/protocol/openid-connect/token HTTP/1.1[\r][\n]"
16:58:31.153 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Content-Length: 283[\r][\n]"
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Content-Type: application/x-www-form-urlencoded; charset=UTF-8[\r][\n]"
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Host: localhost:8080[\r][\n]"
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "Connection: Keep-Alive[\r][\n]"
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - >> "[\r][\n]"
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:278 - >> POST /auth/realms/sunbird/protocol/openid-connect/token HTTP/1.1
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Content-Length: 283
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Content-Type: application/x-www-form-urlencoded; charset=UTF-8
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Host: localhost:8080
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:281 - >> Connection: Keep-Alive
16:58:31.154 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:87 - >> "grant_type=authorization_code&code=1f488c26-afc8-4a41-850c-50bd709717ec.e3981283-d290-429b-b236-41edd943f0aa.c8069a2e-9004-4a85-8dbc-2751984d3fc5&redirect_uri=http%3A%2F%2Flocalhost%3A8081%2Fsso%2Flogin&client_session_state=BC9ECE61BAF46BE729BEC94131F5BF25&client_session_host=posfet"
16:58:31.162 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "HTTP/1.1 400 Bad Request[\r][\n]"
16:58:31.162 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Cache-Control: no-store[\r][\n]"
16:58:31.162 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-XSS-Protection: 1; mode=block[\r][\n]"
16:58:31.162 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Pragma: no-cache[\r][\n]"
16:58:31.162 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-Frame-Options: SAMEORIGIN[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Referrer-Policy: no-referrer[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Date: Sat, 02 Oct 2021 11:28:31 GMT[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Connection: keep-alive[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Strict-Transport-Security: max-age=31536000; includeSubDomains[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "X-Content-Type-Options: nosniff[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Content-Type: application/json[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "Content-Length: 101[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:73 - << "[\r][\n]"
16:58:31.163 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.DefaultClientConnection:259 - Receiving response: HTTP/1.1 400 Bad Request
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:262 - << HTTP/1.1 400 Bad Request
16:58:31.163 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Cache-Control: no-store
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-XSS-Protection: 1; mode=block
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Pragma: no-cache
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-Frame-Options: SAMEORIGIN
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Referrer-Policy: no-referrer
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Date: Sat, 02 Oct 2021 11:28:31 GMT
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Connection: keep-alive
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Strict-Transport-Security: max-age=31536000; includeSubDomains
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << X-Content-Type-Options: nosniff
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Content-Type: application/json
16:58:31.164 [http-nio-8081-exec-1] DEBUG org.apache.http.headers:265 - << Content-Length: 101
16:58:31.164 [http-nio-8081-exec-1] DEBUG o.a.h.impl.client.DefaultHttpClient:504 - Connection can be kept alive indefinitely
16:58:31.165 [http-nio-8081-exec-1] DEBUG org.apache.http.wire:87 - << "{"error":"unauthorized_client","error_description":"INVALID_CREDENTIALS: Invalid client credentials"}"
16:58:31.165 [http-nio-8081-exec-1] DEBUG o.a.h.i.c.t.ThreadSafeClientConnManager:286 - Released connection is reusable.
16:58:31.165 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:431 - Releasing connection [{}->http://localhost:8080][null]
16:58:31.165 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:457 - Pooling connection [{}->http://localhost:8080][null]; keep alive indefinitely
16:58:31.165 [http-nio-8081-exec-1] DEBUG o.a.h.i.conn.tsccm.ConnPoolByRoute:678 - Notifying no-one, there are no waiting threads
16:58:31.166 [http-nio-8081-exec-1] ERROR o.k.a.OAuthRequestAuthenticator:337 - failed to turn code into token
16:58:31.166 [http-nio-8081-exec-1] ERROR o.k.a.OAuthRequestAuthenticator:338 - status from server: 400
16:58:31.166 [http-nio-8081-exec-1] ERROR o.k.a.OAuthRequestAuthenticator:340 - {"error":"unauthorized_client","error_description":"INVALID_CREDENTIALS: Invalid client credentials"}
16:58:31.166 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:155 - Auth outcome: FAILED
16:58:31.170 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:345 - Authentication request failed: org.keycloak.adapters.springsecurity.KeycloakAuthenticationException: Invalid authorization header, see WWW-Authenticate header for details
org.keycloak.adapters.springsecurity.KeycloakAuthenticationException: Invalid authorization header, see WWW-Authenticate header for details
at org.keycloak.adapters.springsecurity.filter.KeycloakAuthenticationProcessingFilter.attemptAuthentication(KeycloakAuthenticationProcessingFilter.java:162)
at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:212)
at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:334)
at org.keycloak.adapters.springsecurity.filter.KeycloakPreAuthActionsFilter.doFilter(KeycloakPreAuthActionsFilter.java:96)
at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:334)
at org.springframework.security.web.header.HeaderWriterFilter.doHeadersAfter(HeaderWriterFilter.java:92)
at org.springframework.security.web.header.HeaderWriterFilter.doFilterInternal(HeaderWriterFilter.java:77)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:334)
at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:105)
at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:334)
at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:56)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:334)
at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:215)
at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:178)
at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:358)
at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:271)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
at org.springframework.web.filter.FormContentFilter.doFilterInternal(FormContentFilter.java:93)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:202)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:97)
at org.keycloak.adapters.tomcat.AbstractAuthenticatedActionsValve.invoke(AbstractAuthenticatedActionsValve.java:67)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:542)
at org.keycloak.adapters.tomcat.AbstractKeycloakAuthenticatorValve.invoke(AbstractKeycloakAuthenticatorValve.java:181)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:143)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:78)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:357)
at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:374)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:893)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1707)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.lang.Thread.run(Thread.java:748)
16:58:31.170 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:346 - Updated SecurityContextHolder to contain null Authentication
16:58:31.170 [http-nio-8081-exec-1] DEBUG o.k.a.s.f.KeycloakAuthenticationProcessingFilter:347 - Delegating to authentication failure handler org.keycloak.adapters.springsecurity.authentication.KeycloakAuthenticationFailureHandler@69a90e
16:58:31.179 [http-nio-8081-exec-1] DEBUG o.k.adapters.PreAuthActionsHandler:74 - adminRequest http://localhost:8081/error?state=3fc578f0-7910-4f1e-bf41-85eb3d23bd89&session_state=e3981283-d290-429b-b236-41edd943f0aa&code=1f488c26-afc8-4a41-850c-50bd709717ec.e3981283-d290-429b-b236-41edd943f0aa.c8069a2e-9004-4a85-8dbc-2751984d3fc5
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment