Skip to content

Instantly share code, notes, and snippets.

@calebgerlach
Created January 5, 2015 14:40
Show Gist options
  • Save calebgerlach/7744c7f8e01184502c82 to your computer and use it in GitHub Desktop.
Save calebgerlach/7744c7f8e01184502c82 to your computer and use it in GitHub Desktop.
[INFO] Scanning for projects...
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Build Order:
[INFO]
[INFO] API Secure
[INFO] API Secure - resource server library
[INFO] API Secure - authorization server
[INFO] API Secure - conext authentication plugin
[INFO] API Secure - example resource server
[INFO] API Secure - mock openconext group api
[INFO] API Secure - authorization server webapp
[INFO] API Secure - example resource server war
[INFO] API Secure - example client app war
[INFO] API Secure - authorization server webapp dist
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building API Secure 1.3.6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ apis-parent ---
[INFO]
[INFO] >>> maven-source-plugin:2.1.2:jar (attach-sources) @ apis-parent >>>
[INFO]
[INFO] <<< maven-source-plugin:2.1.2:jar (attach-sources) @ apis-parent <<<
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar (attach-sources) @ apis-parent ---
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ apis-parent ---
[INFO] Installing E:\github\apis\pom.xml to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-parent\1.3.6-SNAPSHOT\apis-parent-1.3.6-SNAPSHOT.pom
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building API Secure - resource server library 1.3.6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ apis-resource-server-library ---
[INFO] Deleting E:\github\apis\apis-resource-server-library\target
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ apis-resource-server-library ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory E:\github\apis\apis-resource-server-library\src\main\resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ apis-resource-server-library ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 7 source files to E:\github\apis\apis-resource-server-library\target\classes
[INFO]
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ apis-resource-server-library ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory E:\github\apis\apis-resource-server-library\src\test\resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ apis-resource-server-library ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 4 source files to E:\github\apis\apis-resource-server-library\target\test-classes
[INFO]
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ apis-resource-server-library ---
[INFO] Surefire report directory: E:\github\apis\apis-resource-server-library\target\surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.surfnet.oaaas.auth.AuthorizationServerFilterTest
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2015-01-05 09:38:11.953:INFO:oejs.Server:jetty-8.0.4.v20111024
2015-01-05 09:38:11.969:INFO:oejs.AbstractConnector:Started SocketConnector@0.0.0.0:8088 STARTING
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.096 sec
Running org.surfnet.oaaas.auth.principal.AuthenticatedPrincipalTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.surfnet.oaaas.auth.principal.BasicAuthCredentialsTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.surfnet.oaaas.model.TokenResponseCacheTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.562 sec
Results :
Tests run: 8, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ apis-resource-server-library ---
[INFO] Building jar: E:\github\apis\apis-resource-server-library\target\apis-resource-server-library-1.3.6-SNAPSHOT.jar
[INFO]
[INFO] >>> maven-source-plugin:2.1.2:jar (attach-sources) @ apis-resource-server-library >>>
[INFO]
[INFO] <<< maven-source-plugin:2.1.2:jar (attach-sources) @ apis-resource-server-library <<<
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar (attach-sources) @ apis-resource-server-library ---
[INFO] Building jar: E:\github\apis\apis-resource-server-library\target\apis-resource-server-library-1.3.6-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ apis-resource-server-library ---
[INFO] Installing E:\github\apis\apis-resource-server-library\target\apis-resource-server-library-1.3.6-SNAPSHOT.jar to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-resource-server-library\1.3.6-SNAPSHOT\apis-resource-server-library-1.3.6-SNAPSHOT.jar
[INFO] Installing E:\github\apis\apis-resource-server-library\pom.xml to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-resource-server-library\1.3.6-SNAPSHOT\apis-resource-server-library-1.3.6-SNAPSHOT.pom
[INFO] Installing E:\github\apis\apis-resource-server-library\target\apis-resource-server-library-1.3.6-SNAPSHOT-sources.jar to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-resource-server-library\1.3.6-SNAPSHOT\apis-resource-server-library-1.3.6-SNAPSHOT-sources.jar
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building API Secure - authorization server 1.3.6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ apis-authorization-server ---
[INFO] Deleting E:\github\apis\apis-authorization-server\target
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ apis-authorization-server ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 1 resource
[INFO]
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ apis-authorization-server ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 46 source files to E:\github\apis\apis-authorization-server\target\classes
[WARNING] /E:/github/apis/apis-authorization-server/src/main/java/org/surfnet/oaaas/resource/resourceserver/AbstractResource.java: E:\github\apis\apis-authorization-server\src\main\java\org\surfnet\oaaas\resource\resourceserver\AbstractResource.java uses unchecked or unsafe operations.
[WARNING] /E:/github/apis/apis-authorization-server/src/main/java/org/surfnet/oaaas/resource/resourceserver/AbstractResource.java: Recompile with -Xlint:unchecked for details.
[INFO]
[INFO] --- openjpa-maven-plugin:2.2.0:enhance (enhancer) @ apis-authorization-server ---
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.ResourceServer".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.AbstractEntity".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.StatisticsResponse".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.StatisticsResponse$ResourceServerStat".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.AccessTokenRequest".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.AccessTokenResponse".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.ValidationErrorResponse".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.Client".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.AccessToken".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.ResourceOwner".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.ErrorResponse".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.AuthorizationRequest".
[INFO] Enhancer running on type "class org.surfnet.oaaas.model.StatisticsResponse$ClientStat".
[INFO] No metadata was found for 7 classes; they have been enhanced as persistence-aware. If you intended for any of these classes to be persistence-capable, then this means that OpenJPA could not find any metadata for those classes. This can happen if the directory containing your metadata is not in your CLASSPATH, or if your metadata files are not named properly. See the documentation on metadata placement for more information. Persistence-aware classes: [class org.surfnet.oaaas.model.AccessTokenResponse, class org.surfnet.oaaas.model.AccessTokenRequest, class org.surfnet.oaaas.model.ValidationErrorResponse, class org.surfnet.oaaas.model.ErrorResponse, class org.surfnet.oaaas.model.StatisticsResponse, class org.surfnet.oaaas.model.StatisticsResponse$ClientStat, class org.surfnet.oaaas.model.StatisticsResponse$ResourceServerStat]
[INFO]
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ apis-authorization-server ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 6 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ apis-authorization-server ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 18 source files to E:\github\apis\apis-authorization-server\target\test-classes
[WARNING] /E:/github/apis/apis-authorization-server/src/test/java/org/surfnet/oaaas/resource/resourceserver/ResourceOwnerResourceTest.java: Some input files use unchecked or unsafe operations.
[WARNING] /E:/github/apis/apis-authorization-server/src/test/java/org/surfnet/oaaas/resource/resourceserver/ResourceOwnerResourceTest.java: Recompile with -Xlint:unchecked for details.
[INFO]
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ apis-authorization-server ---
[INFO] Surefire report directory: E:\github\apis\apis-authorization-server\target\surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.surfnet.oaaas.auth.LocalResourceOwnerAuthenticatorTest
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.343 sec
Running org.surfnet.oaaas.auth.OAuth2ValidatorImplTest
Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.156 sec
Running org.surfnet.oaaas.model.AccessTokenTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.343 sec
Running org.surfnet.oaaas.model.ClientTest
09:38:18.530 [main] DEBUG org.jboss.logging - Logging Provider: org.jboss.logging.Slf4jLoggerProvider
09:38:18.546 [main] INFO o.h.validator.internal.util.Version - HV000001: Hibernate Validator 4.3.0.Final
09:38:18.546 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:18.546 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:18.546 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:18.546 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:18.640 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.640 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
09:38:18.640 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.640 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.141 sec
Running org.surfnet.oaaas.model.ResourceOwnerTest
09:38:18.640 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:18.640 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:18.640 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:18.640 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:18.655 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.655 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec
Running org.surfnet.oaaas.model.ResourceServerTest
09:38:18.655 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:18.655 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:18.655 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:18.655 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:18.655 [main] DEBUG o.h.v.i.m.d.ConstraintDescriptorImpl - Adding composing constraint: ConstraintDescriptorImpl{annotation=javax.validation.constraints.Pattern, payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message={javax.validation.constraints.Pattern.message}, payload=[Ljava.lang.Class;@f0ac6b0, flags=[Ljavax.validation.constraints.Pattern$Flag;@c7a5487, regexp=.*, groups=[Ljava.lang.Class;@33437177}}.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - ValidationMessages not found.
09:38:18.672 [main] DEBUG o.h.v.r.PlatformResourceBundleLocator - org.hibernate.validator.ValidationMessages found.
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.017 sec
Running org.surfnet.oaaas.repository.AccessTokenRepositoryTest
09:38:18.737 [main] INFO o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'oaaas'
09:38:18.878 [main] WARN openjpa.Runtime - An error occurred while registering a ClassTransformer with PersistenceUnitInfo: name 'oaaas', root URL [file:/E:/github/apis/apis-authorization-server/target/classes]. The error has been consumed. To see it, set your openjpa.Runtime log level to TRACE. Load-time class transformation will not be available.
09:38:18.878 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:18.878 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:18.878 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:18.878 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:18.878 [main] INFO openjpa.Runtime - OpenJPA dynamically loaded a validation provider.
09:38:18.909 [main] INFO openjpa.Runtime - Starting OpenJPA 2.2.0
09:38:19.377 [main] INFO openjpa.jdbc.JDBC - Using dictionary class "org.apache.openjpa.jdbc.sql.HSQLDictionary" (HSQL Database Engine 2.2.8 ,HSQL Database Engine Driver 2.2.8).
09:38:19.470 [main] WARN openjpa.Runtime - An unrecognized EntityManager property was passed to createEntityManager and will be ignored. Key: "openjpa.ConnectionFactoryProperties", Value: "class java.lang.String:PrintParameters=true".
09:38:19.502 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: HSQL Database Engine 2.2
09:38:19.502 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
09:38:19.502 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: PUBLIC
09:38:19.517 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "PUBLIC" already exists. Skipping schema creation.
09:38:19.548 [main] INFO c.g.f.c.m.MetaDataTableImpl - Creating Metadata table: "PUBLIC"."schema_version"
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 17: CREATE TABLE "PUBLIC"."schema_version" (
"version_rank" INT NOT NULL,
"installed_rank" INT NOT NULL,
"version" VARCHAR(50) NOT NULL,
"description" VARCHAR(200) NOT NULL,
"type" VARCHAR(20) NOT NULL,
"script" VARCHAR(1000) NOT NULL,
"checksum" INT,
"installed_by" VARCHAR(100) NOT NULL,
"installed_on" TIMESTAMP DEFAULT CURRENT_TIMESTAMP NOT NULL,
"execution_time" INT NOT NULL,
"success" BIT NOT NULL
)
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 30: ALTER TABLE "PUBLIC"."schema_version" ADD CONSTRAINT "schema_version_pk" PRIMARY KEY ("version")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 32: CREATE INDEX "PUBLIC"."schema_version_vr_idx" ON "PUBLIC"."schema_version" ("version_rank")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 33: CREATE INDEX "PUBLIC"."schema_version_ir_idx" ON "PUBLIC"."schema_version" ("installed_rank")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 34: CREATE INDEX "PUBLIC"."schema_version_s_idx" ON "PUBLIC"."schema_version" ("success")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE TABLE "PUBLIC"."schema_version" (
"version_rank" INT NOT NULL,
"installed_rank" INT NOT NULL,
"version" VARCHAR(50) NOT NULL,
"description" VARCHAR(200) NOT NULL,
"type" VARCHAR(20) NOT NULL,
"script" VARCHAR(1000) NOT NULL,
"checksum" INT,
"installed_by" VARCHAR(100) NOT NULL,
"installed_on" TIMESTAMP DEFAULT CURRENT_TIMESTAMP NOT NULL,
"execution_time" INT NOT NULL,
"success" BIT NOT NULL
)
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: ALTER TABLE "PUBLIC"."schema_version" ADD CONSTRAINT "schema_version_pk" PRIMARY KEY ("version")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "PUBLIC"."schema_version_vr_idx" ON "PUBLIC"."schema_version" ("version_rank")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "PUBLIC"."schema_version_ir_idx" ON "PUBLIC"."schema_version" ("installed_rank")
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX "PUBLIC"."schema_version_s_idx" ON "PUBLIC"."schema_version" ("success")
09:38:19.548 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - Metadata table "PUBLIC"."schema_version" created.
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:19.548 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:19.564 [main] DEBUG c.g.flyway.core.util.FeatureDetector - Spring Jdbc available: true
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/hsqldb' (Prefix: 'V', Suffix: '.sql')
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:19.564 [main] DEBUG c.g.flyway.core.util.FeatureDetector - JBoss VFS v2 available: false
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V0__initial.sql
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V1__auth-server-admin.sql
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_1__add_resowner.sql
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_2__insert_resowner.sql
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:19.564 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:19.580 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:19.580 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:19.580 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:19.580 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:19.580 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "PUBLIC"."schema_version"
09:38:19.595 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
09:38:19.611 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
09:38:19.611 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "PUBLIC": << Empty Schema >>
09:38:19.611 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "PUBLIC" to version 0
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 1: CREATE MEMORY TABLE PUBLIC.ABSTRACTENTITY(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,DTYPE VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 2: CREATE INDEX I_BSTRTTY_DTYPE ON PUBLIC.ABSTRACTENTITY(DTYPE)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 3: CREATE MEMORY TABLE PUBLIC.ACCESSTOKEN(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,ENCODEDPRINCIPAL VARCHAR(16384),EXPIRES BIGINT,REFRESHTOKEN VARCHAR(255),RESOURCEOWNERID VARCHAR(255),TOKEN VARCHAR(255),CLIENT_ID BIGINT NOT NULL,CONSTRAINT U_CCSSTKN_REFRESHTOKEN UNIQUE(REFRESHTOKEN),CONSTRAINT U_CCSSTKN_TOKEN UNIQUE(TOKEN))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 4: CREATE INDEX I_CCSSTKN_CLIENT ON PUBLIC.ACCESSTOKEN(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 5: CREATE MEMORY TABLE PUBLIC.ACCESSTOKEN_SCOPES(ACCESSTOKEN_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 6: CREATE INDEX I_CCSSCPS_ACCESSTOKEN_ID ON PUBLIC.ACCESSTOKEN_SCOPES(ACCESSTOKEN_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 7: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,AUTHSTATE VARCHAR(255),AUTHORIZATIONCODE VARCHAR(255),ENCODEDPRINCIPAL VARCHAR(16384),REDIRECTURI VARCHAR(255),RESPONSETYPE VARCHAR(255),STATE VARCHAR(255),CLIENT_ID BIGINT NOT NULL)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 8: CREATE INDEX I_THRZQST_CLIENT ON PUBLIC.AUTHORIZATIONREQUEST(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 9: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST_GRANTEDSCOPES(AUTHORIZATIONREQUEST_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 10: CREATE INDEX I_THRZCPS_AUTHORIZATIONREQUEST_ID ON PUBLIC.AUTHORIZATIONREQUEST_GRANTEDSCOPES(AUTHORIZATIONREQUEST_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 11: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST_REQUESTEDSCOPES(AUTHORIZATIONREQUEST_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 12: CREATE INDEX I_THRZCPS_AUTHORIZATIONREQUEST_ID1 ON PUBLIC.AUTHORIZATIONREQUEST_REQUESTEDSCOPES(AUTHORIZATIONREQUEST_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 13: CREATE MEMORY TABLE PUBLIC.CLIENT(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,CLIENTID VARCHAR(255),CONTACTEMAIL VARCHAR(255),CONTACTNAME VARCHAR(255),DESCRIPTION VARCHAR(255),EXPIREDURATION BIGINT,CLIENTNAME VARCHAR(255),ALLOWEDIMPLICITGRANT BIT(1),ALLOWEDCLIENTCREDENTIALS BIT(1),SECRET VARCHAR(255),SKIPCONSENT BIT(1),INCLUDEPRINCIPAL BIT(1),THUMBNAILURL VARCHAR(255),USEREFRESHTOKENS BIT(1),RESOURCESERVER_ID BIGINT NOT NULL,CONSTRAINT U_CLIENT_CLIENTID UNIQUE(CLIENTID))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 14: CREATE INDEX I_CLIENT_RESOURCESERVER ON PUBLIC.CLIENT(RESOURCESERVER_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 15: CREATE MEMORY TABLE PUBLIC.CLIENT_ATTRIBUTES(CLIENT_ID BIGINT,ATTRIBUTE_NAME VARCHAR(255) NOT NULL,ATTRIBUTE_VALUE VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 16: CREATE INDEX I_CLNTBTS_CLIENT_ID ON PUBLIC.CLIENT_ATTRIBUTES(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 17: CREATE MEMORY TABLE PUBLIC.CLIENT_REDIRECTURIS(CLIENT_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 18: CREATE INDEX I_CLNTTRS_CLIENT_ID ON PUBLIC.CLIENT_REDIRECTURIS(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 19: CREATE MEMORY TABLE PUBLIC.CLIENT_SCOPES(CLIENT_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 20: CREATE INDEX I_CLNTCPS_CLIENT_ID ON PUBLIC.CLIENT_SCOPES(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 21: CREATE MEMORY TABLE PUBLIC.OPENJPA_SEQUENCE_TABLE(ID TINYINT NOT NULL PRIMARY KEY,SEQUENCE_VALUE BIGINT)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 22: CREATE MEMORY TABLE PUBLIC.RESOURCESERVER(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,CONTACTEMAIL VARCHAR(255),CONTACTNAME VARCHAR(255) NOT NULL,DESCRIPTION VARCHAR(255),RESOURCESERVERKEY VARCHAR(255),RESOURCESERVERNAME VARCHAR(255),OWNER VARCHAR(255),SECRET VARCHAR(255) NOT NULL,THUMBNAILURL VARCHAR(255),CONSTRAINT U_RSRCRVR_KEY UNIQUE(RESOURCESERVERKEY),CONSTRAINT U_RSRCRVR_OWNER UNIQUE(OWNER,RESOURCESERVERNAME))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 23: CREATE MEMORY TABLE PUBLIC.RESOURCESERVER_SCOPES(RESOURCESERVER_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 24: CREATE INDEX I_RSRCCPS_RESOURCESERVER_ID ON PUBLIC.RESOURCESERVER_SCOPES(RESOURCESERVER_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.ABSTRACTENTITY(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,DTYPE VARCHAR(255))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_BSTRTTY_DTYPE ON PUBLIC.ABSTRACTENTITY(DTYPE)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.ACCESSTOKEN(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,ENCODEDPRINCIPAL VARCHAR(16384),EXPIRES BIGINT,REFRESHTOKEN VARCHAR(255),RESOURCEOWNERID VARCHAR(255),TOKEN VARCHAR(255),CLIENT_ID BIGINT NOT NULL,CONSTRAINT U_CCSSTKN_REFRESHTOKEN UNIQUE(REFRESHTOKEN),CONSTRAINT U_CCSSTKN_TOKEN UNIQUE(TOKEN))
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CCSSTKN_CLIENT ON PUBLIC.ACCESSTOKEN(CLIENT_ID)
09:38:19.611 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.ACCESSTOKEN_SCOPES(ACCESSTOKEN_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CCSSCPS_ACCESSTOKEN_ID ON PUBLIC.ACCESSTOKEN_SCOPES(ACCESSTOKEN_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,AUTHSTATE VARCHAR(255),AUTHORIZATIONCODE VARCHAR(255),ENCODEDPRINCIPAL VARCHAR(16384),REDIRECTURI VARCHAR(255),RESPONSETYPE VARCHAR(255),STATE VARCHAR(255),CLIENT_ID BIGINT NOT NULL)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_THRZQST_CLIENT ON PUBLIC.AUTHORIZATIONREQUEST(CLIENT_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST_GRANTEDSCOPES(AUTHORIZATIONREQUEST_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_THRZCPS_AUTHORIZATIONREQUEST_ID ON PUBLIC.AUTHORIZATIONREQUEST_GRANTEDSCOPES(AUTHORIZATIONREQUEST_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.AUTHORIZATIONREQUEST_REQUESTEDSCOPES(AUTHORIZATIONREQUEST_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_THRZCPS_AUTHORIZATIONREQUEST_ID1 ON PUBLIC.AUTHORIZATIONREQUEST_REQUESTEDSCOPES(AUTHORIZATIONREQUEST_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.CLIENT(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,CLIENTID VARCHAR(255),CONTACTEMAIL VARCHAR(255),CONTACTNAME VARCHAR(255),DESCRIPTION VARCHAR(255),EXPIREDURATION BIGINT,CLIENTNAME VARCHAR(255),ALLOWEDIMPLICITGRANT BIT(1),ALLOWEDCLIENTCREDENTIALS BIT(1),SECRET VARCHAR(255),SKIPCONSENT BIT(1),INCLUDEPRINCIPAL BIT(1),THUMBNAILURL VARCHAR(255),USEREFRESHTOKENS BIT(1),RESOURCESERVER_ID BIGINT NOT NULL,CONSTRAINT U_CLIENT_CLIENTID UNIQUE(CLIENTID))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CLIENT_RESOURCESERVER ON PUBLIC.CLIENT(RESOURCESERVER_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.CLIENT_ATTRIBUTES(CLIENT_ID BIGINT,ATTRIBUTE_NAME VARCHAR(255) NOT NULL,ATTRIBUTE_VALUE VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CLNTBTS_CLIENT_ID ON PUBLIC.CLIENT_ATTRIBUTES(CLIENT_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.CLIENT_REDIRECTURIS(CLIENT_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CLNTTRS_CLIENT_ID ON PUBLIC.CLIENT_REDIRECTURIS(CLIENT_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.CLIENT_SCOPES(CLIENT_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_CLNTCPS_CLIENT_ID ON PUBLIC.CLIENT_SCOPES(CLIENT_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.OPENJPA_SEQUENCE_TABLE(ID TINYINT NOT NULL PRIMARY KEY,SEQUENCE_VALUE BIGINT)
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.RESOURCESERVER(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,CONTACTEMAIL VARCHAR(255),CONTACTNAME VARCHAR(255) NOT NULL,DESCRIPTION VARCHAR(255),RESOURCESERVERKEY VARCHAR(255),RESOURCESERVERNAME VARCHAR(255),OWNER VARCHAR(255),SECRET VARCHAR(255) NOT NULL,THUMBNAILURL VARCHAR(255),CONSTRAINT U_RSRCRVR_KEY UNIQUE(RESOURCESERVERKEY),CONSTRAINT U_RSRCRVR_OWNER UNIQUE(OWNER,RESOURCESERVERNAME))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.RESOURCESERVER_SCOPES(RESOURCESERVER_ID BIGINT,ELEMENT VARCHAR(255))
09:38:19.626 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_RSRCCPS_RESOURCESERVER_ID ON PUBLIC.RESOURCESERVER_SCOPES(RESOURCESERVER_ID)
09:38:19.626 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "PUBLIC" to version 0
09:38:19.626 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "PUBLIC" to version 0 (execution time 00:00.015s)
09:38:19.626 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "PUBLIC"."schema_version" successfully updated to reflect changes
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:19.642 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "PUBLIC" to version 1
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 1: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey,secret )
VALUES
(99999, 'foo@university.org','foo.bar','university-foo','university-foo',
'58b749f7-acb3-44b7-a38c-53d5ad740cf6')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 5: INSERT INTO Resourceserver_scopes values (99999, 'read')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 7: INSERT INTO client (id, clientId, contactEmail, contactName, description, expireDuration,
clientName, secret, skipConsent, includePrincipal, thumbNailUrl,
useRefreshTokens, resourceserver_id)
VALUES
(99999, 'cool_app_id', 'client@coolapp.com', 'john.doe', 'Cool app for doing awesome things', 0,
'cool-app', 'secret', 0, 1, 'http://www.surfnet.nl/SURFnet%20imagebank/Logos/SURFconext_klein.gif',
0, 99999)
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 14: INSERT INTO Client_scopes values (99999, 'read')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 15: INSERT INTO Client_redirectUris values (99999, 'http://localhost:8084/redirect')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 21: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES
(99999, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"emma.blunt","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'74eccf5f-0995-4e1c-b08c-d05dd5a0f89b',99999, 'emma.blunt')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 25: INSERT INTO Accesstoken_scopes values (99999, 'read')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 27: INSERT INTO client_attributes(client_id , attribute_name, attribute_value) values
(99999, 'university','foo-university')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 33: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey, secret, owner, thumbNailUrl)
VALUES
(99998, 'localadmin@example.com','local admin','Authorization Server Apis',
'authorization-server-admin', 'cafebabe-cafe-babe-cafe-babecafebabe', null, 'https://raw.github.com/OpenConextApps/apis/master/apis-images/surf-oauth.png')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 37: INSERT INTO ResourceServer_scopes values (99998, 'read'),(99998, 'write')
09:38:19.642 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 39: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99998, 'client@coolapp.com', 'john.doe', 'Javascript application for authorization server administration',
'Authorization Server Admin Client',
'https://raw.github.com/OpenConextApps/apis/master/apis-images/surf-oauth-client.png', 99998,
'authorization-server-admin-js-client', '')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 46: INSERT INTO Client_scopes values (99998, 'read'), (99998, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 51: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey, secret, owner)
VALUES
(99997, 'it-test@example.com','it test','it-test-resource-server',
'it-test-resource-server', 'somesecret', 'it-test-enduser')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 55: INSERT INTO Resourceserver_scopes values (99997, 'read')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 60: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99997, 'it-test@example.com', 'john.doe', 'it test client',
'it test client',
'thumbnailurl', 99997,
'it-test-client', 'somesecret')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 67: INSERT INTO Client_scopes values (99997, 'read'), (99997, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 72: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret, skipConsent, expireDuration, useRefreshTokens, allowedImplicitGrant)
VALUES
(99996, 'it-test@example.com', 'john.doe', 'it test client no consent use refresh',
'it test client 2',
'thumbnailurl', 99997,
'it-test-client-no-consent-refresh', 'somesecret2', 1, 3600, 1, 1)
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 79: INSERT INTO Client_scopes values (99996, 'read'), (99996, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 84: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99995, 'it-test-grant@example.com', 'john.grant', 'it test client grant',
'it test client grant',
'thumbnailurl', 99997,
'it-test-client-grant', 'somesecret-grant')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 91: INSERT INTO Client_scopes values (99995, 'read'), (99995, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 96: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES (99998, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"admin-enduser","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'dad30fb8-ad90-4f24-af99-798bb71d27c8',99998, 'admin-enduser')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 99: INSERT INTO Accesstoken_scopes values (99998, 'read'), (99998, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 103: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES
(99997, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"it-test-enduser","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'00-11-22-33',99997, 'it-test-enduser ')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 107: INSERT INTO Accesstoken_scopes values (99997, 'read'), (99997, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey,secret )
VALUES
(99999, 'foo@university.org','foo.bar','university-foo','university-foo',
'58b749f7-acb3-44b7-a38c-53d5ad740cf6')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Resourceserver_scopes values (99999, 'read')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client (id, clientId, contactEmail, contactName, description, expireDuration,
clientName, secret, skipConsent, includePrincipal, thumbNailUrl,
useRefreshTokens, resourceserver_id)
VALUES
(99999, 'cool_app_id', 'client@coolapp.com', 'john.doe', 'Cool app for doing awesome things', 0,
'cool-app', 'secret', 0, 1, 'http://www.surfnet.nl/SURFnet%20imagebank/Logos/SURFconext_klein.gif',
0, 99999)
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_scopes values (99999, 'read')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_redirectUris values (99999, 'http://localhost:8084/redirect')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES
(99999, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"emma.blunt","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'74eccf5f-0995-4e1c-b08c-d05dd5a0f89b',99999, 'emma.blunt')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Accesstoken_scopes values (99999, 'read')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client_attributes(client_id , attribute_name, attribute_value) values
(99999, 'university','foo-university')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey, secret, owner, thumbNailUrl)
VALUES
(99998, 'localadmin@example.com','local admin','Authorization Server Apis',
'authorization-server-admin', 'cafebabe-cafe-babe-cafe-babecafebabe', null, 'https://raw.github.com/OpenConextApps/apis/master/apis-images/surf-oauth.png')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO ResourceServer_scopes values (99998, 'read'),(99998, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99998, 'client@coolapp.com', 'john.doe', 'Javascript application for authorization server administration',
'Authorization Server Admin Client',
'https://raw.github.com/OpenConextApps/apis/master/apis-images/surf-oauth-client.png', 99998,
'authorization-server-admin-js-client', '')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_scopes values (99998, 'read'), (99998, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO resourceserver (id, contactEmail, contactName, resourceServerName, resourceServerKey, secret, owner)
VALUES
(99997, 'it-test@example.com','it test','it-test-resource-server',
'it-test-resource-server', 'somesecret', 'it-test-enduser')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Resourceserver_scopes values (99997, 'read')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99997, 'it-test@example.com', 'john.doe', 'it test client',
'it test client',
'thumbnailurl', 99997,
'it-test-client', 'somesecret')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_scopes values (99997, 'read'), (99997, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret, skipConsent, expireDuration, useRefreshTokens, allowedImplicitGrant)
VALUES
(99996, 'it-test@example.com', 'john.doe', 'it test client no consent use refresh',
'it test client 2',
'thumbnailurl', 99997,
'it-test-client-no-consent-refresh', 'somesecret2', 1, 3600, 1, 1)
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_scopes values (99996, 'read'), (99996, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO client (id, contactEmail, contactName, description, clientName, thumbNailUrl, resourceserver_id,
clientId, secret)
VALUES
(99995, 'it-test-grant@example.com', 'john.grant', 'it test client grant',
'it test client grant',
'thumbnailurl', 99997,
'it-test-client-grant', 'somesecret-grant')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Client_scopes values (99995, 'read'), (99995, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES (99998, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"admin-enduser","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'dad30fb8-ad90-4f24-af99-798bb71d27c8',99998, 'admin-enduser')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Accesstoken_scopes values (99998, 'read'), (99998, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO accesstoken (id, expires, encodedPrincipal, token, client_id, resourceOwnerId)
VALUES
(99997, 0, '["org.surfnet.oaaas.auth.principal.AuthenticatedPrincipal",{"name":"it-test-enduser","roles":["java.util.ArrayList",[]],"groups":["java.util.ArrayList",[]],"attributes":["java.util.HashMap",{}]}]',
'00-11-22-33',99997, 'it-test-enduser ')
09:38:19.658 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO Accesstoken_scopes values (99997, 'read'), (99997, 'write')
09:38:19.658 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "PUBLIC" to version 1
09:38:19.658 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "PUBLIC" to version 1 (execution time 00:00.016s)
09:38:19.673 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "PUBLIC"."schema_version" successfully updated to reflect changes
09:38:19.673 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:19.673 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:19.673 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:19.673 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:19.689 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "PUBLIC" to version 2.1
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 1: CREATE MEMORY TABLE PUBLIC.RESOURCEOWNER(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,USERNAME VARCHAR(255),PASSWORD VARCHAR(255),CONSTRAINT U_ROWN_USERNAME UNIQUE(USERNAME))
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 2: CREATE INDEX I_RSCOWN_USERNAME ON PUBLIC.RESOURCEOWNER(USERNAME)
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE MEMORY TABLE PUBLIC.RESOURCEOWNER(ID BIGINT NOT NULL PRIMARY KEY,CREATIONDATE TIMESTAMP,MODIFICATIONDATE TIMESTAMP,USERNAME VARCHAR(255),PASSWORD VARCHAR(255),CONSTRAINT U_ROWN_USERNAME UNIQUE(USERNAME))
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: CREATE INDEX I_RSCOWN_USERNAME ON PUBLIC.RESOURCEOWNER(USERNAME)
09:38:19.689 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "PUBLIC" to version 2.1
09:38:19.689 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "PUBLIC" to version 2.1 (execution time 00:00.000s)
09:38:19.689 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "PUBLIC"."schema_version" successfully updated to reflect changes
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:19.689 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:19.704 [main] INFO c.g.flyway.core.command.DbMigrate - Migrating schema "PUBLIC" to version 2.2
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Found statement at line 4: INSERT INTO resourceowner (id, username, password)
VALUES (99999,'emma.blunt', 'cafebabe')
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.SqlScript - Executing SQL: INSERT INTO resourceowner (id, username, password)
VALUES (99999,'emma.blunt', 'cafebabe')
09:38:19.704 [main] DEBUG c.g.flyway.core.command.DbMigrate - Successfully completed and committed migration of schema "PUBLIC" to version 2.2
09:38:19.704 [main] DEBUG c.g.flyway.core.command.DbMigrate - Finished migrating schema "PUBLIC" to version 2.2 (execution time 00:00.000s)
09:38:19.704 [main] DEBUG c.g.f.c.m.MetaDataTableImpl - MetaData table "PUBLIC"."schema_version" successfully updated to reflect changes
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:19.704 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:19.720 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:19.720 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:19.720 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:19.720 [main] INFO c.g.flyway.core.command.DbMigrate - Successfully applied 4 migrations to schema "PUBLIC" (execution time 00:00.187s).
09:38:19.860 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AccessToken_" for entity class org.surfnet.oaaas.model.AccessToken can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AccessToken_"
09:38:19.876 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceOwner_" for entity class org.surfnet.oaaas.model.ResourceOwner can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceOwner_"
09:38:19.876 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AbstractEntity_" for entity class org.surfnet.oaaas.model.AbstractEntity can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AbstractEntity_"
09:38:19.876 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceServer_" for entity class org.surfnet.oaaas.model.ResourceServer can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceServer_"
09:38:19.876 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AuthorizationRequest_" for entity class org.surfnet.oaaas.model.AuthorizationRequest can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AuthorizationRequest_"
09:38:19.876 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.Client_" for entity class org.surfnet.oaaas.model.Client can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.Client_"
09:38:19.907 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByRefreshToken
09:38:19.907 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByRefreshToken
09:38:19.907 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByRefreshToken.count
09:38:19.923 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerIdAndClient
09:38:19.923 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerIdAndClient
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerIdAndClient.count
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceOwnerId
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByIdAndResourceOwnerId
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByIdAndResourceOwnerId.count
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerId
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerId
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerId.count
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method countByUniqueResourceOwnerIdAndClientId
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByMaxExpires
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByTokenAndClient
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByTokenAndClient
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByTokenAndClient.count
09:38:19.938 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByToken
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByToken
09:38:19.938 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByToken.count
09:38:19.938 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@5e25457]
09:38:20.001 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@51c07f7a; candidate class: class org.surfnet.oaaas.model.AccessToken; query: null] with parameters: ?
09:38:20.001 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@51c07f7a; candidate class: class org.surfnet.oaaas.model.AccessToken; query: null] with parameters: ?
09:38:20.016 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1795120084 SELECT t0.id, t0.creationDate, t0.modificationDate, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.expires, t0.refreshToken, t0.resourceOwnerId, t0.token FROM accesstoken t0 INNER JOIN client t1 ON t0.client_id = t1.id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.token = ?) [params=?]
09:38:20.016 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1795120084 SELECT t0.id, t0.creationDate, t0.modificationDate, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.expires, t0.refreshToken, t0.resourceOwnerId, t0.token FROM accesstoken t0 INNER JOIN client t1 ON t0.client_id = t1.id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.token = ?) [params=?]
09:38:20.016 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.016 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.032 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1846325626 SELECT t0.id, t1.element FROM accesstoken t0 INNER JOIN AccessToken_scopes t1 ON t0.id = t1.ACCESSTOKEN_ID WHERE (t0.token = ?) ORDER BY t0.id ASC [params=?]
09:38:20.032 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1846325626 SELECT t0.id, t1.element FROM accesstoken t0 INNER JOIN AccessToken_scopes t1 ON t0.id = t1.ACCESSTOKEN_ID WHERE (t0.token = ?) ORDER BY t0.id ASC [params=?]
09:38:20.032 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.032 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1637189605 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1637189605 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 680644574 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 680644574 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1413215911 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1413215911 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1891583713 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1891583713 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 160779024 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 160779024 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 967022308 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 967022308 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1029545867 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1029545867 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1179182899 SELECT t0.element FROM Client_redirectUris t0 WHERE t0.CLIENT_ID = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 1179182899 SELECT t0.element FROM Client_redirectUris t0 WHERE t0.CLIENT_ID = ? [params=?]
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.048 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.063 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 699582149 SELECT t0.element FROM Client_scopes t0 WHERE t0.CLIENT_ID = ? [params=?]
09:38:20.063 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 699582149 SELECT t0.element FROM Client_scopes t0 WHERE t0.CLIENT_ID = ? [params=?]
09:38:20.063 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.063 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.079 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByRefreshToken
09:38:20.079 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByRefreshToken
09:38:20.079 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByRefreshToken.count
09:38:20.079 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerIdAndClient
09:38:20.079 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerIdAndClient
09:38:20.079 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerIdAndClient.count
09:38:20.094 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceOwnerId
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByIdAndResourceOwnerId
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByIdAndResourceOwnerId.count
09:38:20.094 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerId
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerId
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerId.count
09:38:20.094 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method countByUniqueResourceOwnerIdAndClientId
09:38:20.094 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByMaxExpires
09:38:20.094 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByTokenAndClient
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByTokenAndClient
09:38:20.094 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByTokenAndClient.count
09:38:20.110 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByToken
09:38:20.110 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByToken
09:38:20.110 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByToken.count
09:38:20.110 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@2339d5db]
09:38:20.110 [main] TRACE openjpa.Query - Executing query: [select count(distinct resourceOwnerId) from accesstoken where client_id = ?] with parameters: ?
09:38:20.110 [main] TRACE openjpa.Query - Executing query: [select count(distinct resourceOwnerId) from accesstoken where client_id = ?] with parameters: ?
09:38:20.110 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 2009530272 select count(distinct resourceOwnerId) from accesstoken where client_id = ? [params=?]
09:38:20.110 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> executing prepstmnt 2009530272 select count(distinct resourceOwnerId) from accesstoken where client_id = ? [params=?]
09:38:20.110 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
09:38:20.110 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 645823041> [0 ms] spent
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.438 sec
Running org.surfnet.oaaas.repository.AuthorizationRequestRepositoryTest
09:38:20.110 [main] INFO o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'oaaas'
09:38:20.126 [main] WARN openjpa.Runtime - An error occurred while registering a ClassTransformer with PersistenceUnitInfo: name 'oaaas', root URL [file:/E:/github/apis/apis-authorization-server/target/classes]. The error has been consumed. To see it, set your openjpa.Runtime log level to TRACE. Load-time class transformation will not be available.
09:38:20.126 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:20.126 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:20.126 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:20.126 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:20.126 [main] INFO openjpa.Runtime - OpenJPA dynamically loaded a validation provider.
09:38:20.126 [main] INFO openjpa.Runtime - Starting OpenJPA 2.2.0
09:38:20.126 [main] INFO openjpa.jdbc.JDBC - Using dictionary class "org.apache.openjpa.jdbc.sql.HSQLDictionary" (HSQL Database Engine 2.2.8 ,HSQL Database Engine Driver 2.2.8).
09:38:20.141 [main] WARN openjpa.Runtime - An unrecognized EntityManager property was passed to createEntityManager and will be ignored. Key: "openjpa.ConnectionFactoryProperties", Value: "class java.lang.String:PrintParameters=true".
09:38:20.141 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: HSQL Database Engine 2.2
09:38:20.141 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
09:38:20.141 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: PUBLIC
09:38:20.141 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "PUBLIC" already exists. Skipping schema creation.
09:38:20.141 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "PUBLIC"."schema_version"
09:38:20.157 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
09:38:20.172 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
09:38:20.172 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:20.172 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/hsqldb' (Prefix: 'V', Suffix: '.sql')
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V0__initial.sql
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V1__auth-server-admin.sql
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_1__add_resowner.sql
09:38:20.172 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_2__insert_resowner.sql
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.188 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.188 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "PUBLIC": 2.2
09:38:20.188 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:20.188 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:20.188 [main] INFO c.g.flyway.core.command.DbMigrate - Schema "PUBLIC" is up to date. No migration necessary.
09:38:20.219 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AccessToken_" for entity class org.surfnet.oaaas.model.AccessToken can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AccessToken_"
09:38:20.235 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceOwner_" for entity class org.surfnet.oaaas.model.ResourceOwner can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceOwner_"
09:38:20.235 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AbstractEntity_" for entity class org.surfnet.oaaas.model.AbstractEntity can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AbstractEntity_"
09:38:20.235 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceServer_" for entity class org.surfnet.oaaas.model.ResourceServer can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceServer_"
09:38:20.235 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AuthorizationRequest_" for entity class org.surfnet.oaaas.model.AuthorizationRequest can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AuthorizationRequest_"
09:38:20.235 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.Client_" for entity class org.surfnet.oaaas.model.Client can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.Client_"
09:38:20.235 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByAuthState
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AuthorizationRequest.findByAuthState
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AuthorizationRequest.findByAuthState.count
09:38:20.235 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByAuthorizationCode
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AuthorizationRequest.findByAuthorizationCode
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AuthorizationRequest.findByAuthorizationCode.count
09:38:20.235 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@70ce676b]
09:38:20.235 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceServer
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByIdAndResourceServer
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByIdAndResourceServer.count
09:38:20.235 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceServer
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByResourceServer
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByResourceServer.count
09:38:20.235 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByClientId
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByClientId
09:38:20.235 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByClientId.count
09:38:20.235 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@71c20878]
09:38:20.250 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@6855723; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.250 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@6855723; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 210025846 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 210025846 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2094299161 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2094299161 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 687608729 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 687608729 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2082678876 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2082678876 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 276530727 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 276530727 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 153892488 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 153892488 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2101603270 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2101603270 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 77851461 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 77851461 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.250 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 340358109 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 340358109 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1649786002 INSERT INTO OPENJPA_SEQUENCE_TABLE (ID, SEQUENCE_VALUE) VALUES (?, ?) [params=?, ?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1649786002 INSERT INTO OPENJPA_SEQUENCE_TABLE (ID, SEQUENCE_VALUE) VALUES (?, ?) [params=?, ?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1315628451 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1315628451 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 993769467 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 993769467 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.282 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2134082761 INSERT INTO authorizationrequest (id, creationDate, modificationDate, authState, authorizationCode, encodedPrincipal, redirectUri, responseType, state, client_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 2134082761 INSERT INTO authorizationrequest (id, creationDate, modificationDate, authState, authorizationCode, encodedPrincipal, redirectUri, responseType, state, client_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 257091933 INSERT INTO AuthorizationRequest_requestedScopes (AUTHORIZATIONREQUEST_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 257091933 INSERT INTO AuthorizationRequest_requestedScopes (AUTHORIZATIONREQUEST_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 207020167 INSERT INTO AuthorizationRequest_requestedScopes (AUTHORIZATIONREQUEST_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 207020167 INSERT INTO AuthorizationRequest_requestedScopes (AUTHORIZATIONREQUEST_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@93e06df; candidate class: class org.surfnet.oaaas.model.AuthorizationRequest; query: null] with parameters: ?
09:38:20.297 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@93e06df; candidate class: class org.surfnet.oaaas.model.AuthorizationRequest; query: null] with parameters: ?
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 428823661 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.authState, t0.authorizationCode, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.redirectUri, t0.responseType, t0.state FROM authorizationrequest t0 INNER JOIN client t1 ON t0.client_id = t1.id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.authState = ?) [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 428823661 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.authState, t0.authorizationCode, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.redirectUri, t0.responseType, t0.state FROM authorizationrequest t0 INNER JOIN client t1 ON t0.client_id = t1.id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.authState = ?) [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1480789822 SELECT t0.id, t1.element FROM authorizationrequest t0 INNER JOIN AuthorizationRequest_grantedScopes t1 ON t0.id = t1.AUTHORIZATIONREQUEST_ID WHERE (t0.authState = ?) ORDER BY t0.id ASC [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 1480789822 SELECT t0.id, t1.element FROM authorizationrequest t0 INNER JOIN AuthorizationRequest_grantedScopes t1 ON t0.id = t1.AUTHORIZATIONREQUEST_ID WHERE (t0.authState = ?) ORDER BY t0.id ASC [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 453550667 SELECT t0.id, t1.element FROM authorizationrequest t0 INNER JOIN AuthorizationRequest_requestedScopes t1 ON t0.id = t1.AUTHORIZATIONREQUEST_ID WHERE (t0.authState = ?) ORDER BY t0.id ASC [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> executing prepstmnt 453550667 SELECT t0.id, t1.element FROM authorizationrequest t0 INNER JOIN AuthorizationRequest_requestedScopes t1 ON t0.id = t1.AUTHORIZATIONREQUEST_ID WHERE (t0.authState = ?) ORDER BY t0.id ASC [params=?]
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
09:38:20.297 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 1588761701> [0 ms] spent
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.187 sec
Running org.surfnet.oaaas.repository.ClientRepositoryTest
09:38:20.313 [main] INFO o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'oaaas'
09:38:20.313 [main] WARN openjpa.Runtime - An error occurred while registering a ClassTransformer with PersistenceUnitInfo: name 'oaaas', root URL [file:/E:/github/apis/apis-authorization-server/target/classes]. The error has been consumed. To see it, set your openjpa.Runtime log level to TRACE. Load-time class transformation will not be available.
09:38:20.313 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:20.313 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:20.313 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:20.313 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:20.313 [main] INFO openjpa.Runtime - OpenJPA dynamically loaded a validation provider.
09:38:20.313 [main] INFO openjpa.Runtime - Starting OpenJPA 2.2.0
09:38:20.313 [main] INFO openjpa.jdbc.JDBC - Using dictionary class "org.apache.openjpa.jdbc.sql.HSQLDictionary" (HSQL Database Engine 2.2.8 ,HSQL Database Engine Driver 2.2.8).
09:38:20.328 [main] WARN openjpa.Runtime - An unrecognized EntityManager property was passed to createEntityManager and will be ignored. Key: "openjpa.ConnectionFactoryProperties", Value: "class java.lang.String:PrintParameters=true".
09:38:20.328 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: HSQL Database Engine 2.2
09:38:20.328 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
09:38:20.328 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: PUBLIC
09:38:20.328 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "PUBLIC" already exists. Skipping schema creation.
09:38:20.328 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "PUBLIC"."schema_version"
09:38:20.344 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
09:38:20.344 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
09:38:20.360 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:20.375 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/hsqldb' (Prefix: 'V', Suffix: '.sql')
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V0__initial.sql
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V1__auth-server-admin.sql
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_1__add_resowner.sql
09:38:20.375 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_2__insert_resowner.sql
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.391 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.391 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "PUBLIC": 2.2
09:38:20.391 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:20.391 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:20.391 [main] INFO c.g.flyway.core.command.DbMigrate - Schema "PUBLIC" is up to date. No migration necessary.
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AccessToken_" for entity class org.surfnet.oaaas.model.AccessToken can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AccessToken_"
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceOwner_" for entity class org.surfnet.oaaas.model.ResourceOwner can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceOwner_"
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AbstractEntity_" for entity class org.surfnet.oaaas.model.AbstractEntity can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AbstractEntity_"
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceServer_" for entity class org.surfnet.oaaas.model.ResourceServer can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceServer_"
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AuthorizationRequest_" for entity class org.surfnet.oaaas.model.AuthorizationRequest can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AuthorizationRequest_"
09:38:20.422 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.Client_" for entity class org.surfnet.oaaas.model.Client can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.Client_"
09:38:20.422 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceServer
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByIdAndResourceServer
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByIdAndResourceServer.count
09:38:20.422 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceServer
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByResourceServer
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByResourceServer.count
09:38:20.422 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByClientId
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByClientId
09:38:20.422 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByClientId.count
09:38:20.422 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@5ce81ff9]
09:38:20.422 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@4f3e8268; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.422 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@4f3e8268; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 756452769 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 756452769 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 635029188 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 635029188 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 433770926 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 433770926 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 642243458 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 642243458 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 20921409 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 20921409 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 2122184288 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 2122184288 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1484935950 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1484935950 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 784631457 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 784631457 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.438 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceServer
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByIdAndResourceServer
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByIdAndResourceServer.count
09:38:20.438 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceServer
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByResourceServer
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByResourceServer.count
09:38:20.438 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByClientId
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByClientId
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByClientId.count
09:38:20.438 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@696504a9]
09:38:20.438 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByOwner
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByOwner
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByOwner.count
09:38:20.438 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndOwner
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByIdAndOwner
09:38:20.438 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByIdAndOwner.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByKey
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByKey
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByKey.count
09:38:20.453 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@3f0fa933]
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByRefreshToken
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByRefreshToken
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByRefreshToken.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerIdAndClient
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerIdAndClient
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerIdAndClient.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceOwnerId
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByIdAndResourceOwnerId
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByIdAndResourceOwnerId.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceOwnerId
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByResourceOwnerId
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByResourceOwnerId.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method countByUniqueResourceOwnerIdAndClientId
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByMaxExpires
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByTokenAndClient
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByTokenAndClient
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByTokenAndClient.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByToken
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AccessToken.findByToken
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AccessToken.findByToken.count
09:38:20.453 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@8a1a5ba]
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByAuthState
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AuthorizationRequest.findByAuthState
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AuthorizationRequest.findByAuthState.count
09:38:20.453 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByAuthorizationCode
09:38:20.453 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query AuthorizationRequest.findByAuthorizationCode
09:38:20.469 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query AuthorizationRequest.findByAuthorizationCode.count
09:38:20.469 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@7405c4]
09:38:20.469 [main] DEBUG o.h.v.i.m.d.ConstraintDescriptorImpl - Adding composing constraint: ConstraintDescriptorImpl{annotation=javax.validation.constraints.Pattern, payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message={javax.validation.constraints.Pattern.message}, payload=[Ljava.lang.Class;@55888bf3, flags=[Ljavax.validation.constraints.Pattern$Flag;@c7a5487, regexp=.*, groups=[Ljava.lang.Class;@779c2a63}}.
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 697314589 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 697314589 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1453891615 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1453891615 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1783803536 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1783803536 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1304379027 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1304379027 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 800516868 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 800516868 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 403759086 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 403759086 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.500 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 127112362 SELECT t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens, t2.CLIENT_ID, t2.element FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id LEFT OUTER JOIN Client_redirectUris t2 ON t0.id = t2.CLIENT_ID WHERE t0.id = ? ORDER BY t2.CLIENT_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 127112362 SELECT t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens, t2.CLIENT_ID, t2.element FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id LEFT OUTER JOIN Client_redirectUris t2 ON t0.id = t2.CLIENT_ID WHERE t0.id = ? ORDER BY t2.CLIENT_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 998372573 SELECT t0.creationDate, t0.modificationDate, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.expires, t0.refreshToken, t0.resourceOwnerId, t0.token, t3.ACCESSTOKEN_ID, t3.element FROM accesstoken t0 INNER JOIN client t1 ON t0.client_id = t1.id LEFT OUTER JOIN AccessToken_scopes t3 ON t0.id = t3.ACCESSTOKEN_ID INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? ORDER BY t3.ACCESSTOKEN_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 998372573 SELECT t0.creationDate, t0.modificationDate, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.expires, t0.refreshToken, t0.resourceOwnerId, t0.token, t3.ACCESSTOKEN_ID, t3.element FROM accesstoken t0 INNER JOIN client t1 ON t0.client_id = t1.id LEFT OUTER JOIN AccessToken_scopes t3 ON t0.id = t3.ACCESSTOKEN_ID INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? ORDER BY t3.ACCESSTOKEN_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1342798431 SELECT t0.creationDate, t0.modificationDate, t0.authState, t0.authorizationCode, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.redirectUri, t0.responseType, t0.state, t3.AUTHORIZATIONREQUEST_ID, t3.element FROM authorizationrequest t0 INNER JOIN client t1 ON t0.client_id = t1.id LEFT OUTER JOIN AuthorizationRequest_grantedScopes t3 ON t0.id = t3.AUTHORIZATIONREQUEST_ID INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? ORDER BY t3.AUTHORIZATIONREQUEST_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> executing prepstmnt 1342798431 SELECT t0.creationDate, t0.modificationDate, t0.authState, t0.authorizationCode, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens, t0.encodedPrincipal, t0.redirectUri, t0.responseType, t0.state, t3.AUTHORIZATIONREQUEST_ID, t3.element FROM authorizationrequest t0 INNER JOIN client t1 ON t0.client_id = t1.id LEFT OUTER JOIN AuthorizationRequest_grantedScopes t3 ON t0.id = t3.AUTHORIZATIONREQUEST_ID INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? ORDER BY t3.AUTHORIZATIONREQUEST_ID ASC [params=?]
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
09:38:20.516 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 598498343> [0 ms] spent
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.219 sec
Running org.surfnet.oaaas.repository.ResourceOwnerRepositoryTest
09:38:20.516 [main] INFO o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'oaaas'
09:38:20.531 [main] WARN openjpa.Runtime - An error occurred while registering a ClassTransformer with PersistenceUnitInfo: name 'oaaas', root URL [file:/E:/github/apis/apis-authorization-server/target/classes]. The error has been consumed. To see it, set your openjpa.Runtime log level to TRACE. Load-time class transformation will not be available.
09:38:20.531 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:20.531 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:20.531 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:20.531 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:20.531 [main] INFO openjpa.Runtime - OpenJPA dynamically loaded a validation provider.
09:38:20.531 [main] INFO openjpa.Runtime - Starting OpenJPA 2.2.0
09:38:20.531 [main] INFO openjpa.jdbc.JDBC - Using dictionary class "org.apache.openjpa.jdbc.sql.HSQLDictionary" (HSQL Database Engine 2.2.8 ,HSQL Database Engine Driver 2.2.8).
09:38:20.547 [main] WARN openjpa.Runtime - An unrecognized EntityManager property was passed to createEntityManager and will be ignored. Key: "openjpa.ConnectionFactoryProperties", Value: "class java.lang.String:PrintParameters=true".
09:38:20.547 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: HSQL Database Engine 2.2
09:38:20.547 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
09:38:20.547 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: PUBLIC
09:38:20.547 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "PUBLIC" already exists. Skipping schema creation.
09:38:20.547 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "PUBLIC"."schema_version"
09:38:20.562 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
09:38:20.562 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
09:38:20.578 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:20.578 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/hsqldb' (Prefix: 'V', Suffix: '.sql')
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V0__initial.sql
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V1__auth-server-admin.sql
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_1__add_resowner.sql
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_2__insert_resowner.sql
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.578 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.578 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "PUBLIC": 2.2
09:38:20.578 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:20.578 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:20.578 [main] INFO c.g.flyway.core.command.DbMigrate - Schema "PUBLIC" is up to date. No migration necessary.
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AccessToken_" for entity class org.surfnet.oaaas.model.AccessToken can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AccessToken_"
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceOwner_" for entity class org.surfnet.oaaas.model.ResourceOwner can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceOwner_"
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AbstractEntity_" for entity class org.surfnet.oaaas.model.AbstractEntity can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AbstractEntity_"
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceServer_" for entity class org.surfnet.oaaas.model.ResourceServer can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceServer_"
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AuthorizationRequest_" for entity class org.surfnet.oaaas.model.AuthorizationRequest can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AuthorizationRequest_"
09:38:20.609 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.Client_" for entity class org.surfnet.oaaas.model.Client can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.Client_"
09:38:20.625 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByUsername
09:38:20.625 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceOwner.findByUsername
09:38:20.625 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceOwner.findByUsername.count
09:38:20.625 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@65a2c304]
09:38:20.625 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@1992cf22; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null] with parameters: ?
09:38:20.625 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@1992cf22; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null] with parameters: ?
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 535550495 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0 WHERE (t0.username = ?) [params=?]
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 535550495 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0 WHERE (t0.username = ?) [params=?]
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
09:38:20.625 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@29c06a2b; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null] with parameters: ?
09:38:20.625 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@29c06a2b; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null] with parameters: ?
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 1537318571 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0 WHERE (t0.username = ?) [params=?]
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 1537318571 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0 WHERE (t0.username = ?) [params=?]
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
09:38:20.625 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByUsername
09:38:20.625 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceOwner.findByUsername
09:38:20.625 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceOwner.findByUsername.count
09:38:20.625 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@c0b435e]
09:38:20.625 [main] TRACE openjpa.Query - Executing query: Query: org.apache.openjpa.kernel.QueryImpl@1607484a; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null
09:38:20.625 [main] TRACE openjpa.Query - Executing query: Query: org.apache.openjpa.kernel.QueryImpl@1607484a; candidate class: class org.surfnet.oaaas.model.ResourceOwner; query: null
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 936264606 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> executing prepstmnt 936264606 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.password, t0.username FROM resourceowner t0
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
09:38:20.625 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 893204888> [0 ms] spent
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.109 sec
Running org.surfnet.oaaas.repository.ResourceServerRepositoryTest
09:38:20.625 [main] INFO o.s.o.j.LocalContainerEntityManagerFactoryBean - Building JPA container EntityManagerFactory for persistence unit 'oaaas'
09:38:20.640 [main] WARN openjpa.Runtime - An error occurred while registering a ClassTransformer with PersistenceUnitInfo: name 'oaaas', root URL [file:/E:/github/apis/apis-authorization-server/target/classes]. The error has been consumed. To see it, set your openjpa.Runtime log level to TRACE. Load-time class transformation will not be available.
09:38:20.640 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Found javax.persistence.Persistence on classpath containing 'getPersistenceUtil'. Assuming JPA 2 environment. Trying to instantiate JPA aware TraversableResolver
09:38:20.640 [main] DEBUG o.h.v.i.e.r.DefaultTraversableResolver - Instantiated JPA aware TraversableResolver of type org.hibernate.validator.internal.engine.resolver.JPATraversableResolver.
09:38:20.640 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
09:38:20.640 [main] DEBUG o.h.v.i.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
09:38:20.640 [main] INFO openjpa.Runtime - OpenJPA dynamically loaded a validation provider.
09:38:20.640 [main] INFO openjpa.Runtime - Starting OpenJPA 2.2.0
09:38:20.640 [main] INFO openjpa.jdbc.JDBC - Using dictionary class "org.apache.openjpa.jdbc.sql.HSQLDictionary" (HSQL Database Engine 2.2.8 ,HSQL Database Engine Driver 2.2.8).
09:38:20.656 [main] WARN openjpa.Runtime - An unrecognized EntityManager property was passed to createEntityManager and will be ignored. Key: "openjpa.ConnectionFactoryProperties", Value: "class java.lang.String:PrintParameters=true".
09:38:20.656 [main] DEBUG c.g.f.c.dbsupport.DbSupportFactory - Database: HSQL Database Engine 2.2
09:38:20.656 [main] DEBUG com.googlecode.flyway.core.Flyway - DDL Transactions Supported: false
09:38:20.656 [main] DEBUG com.googlecode.flyway.core.Flyway - Schema: PUBLIC
09:38:20.656 [main] DEBUG c.g.flyway.core.command.DbSchemas - Schema "PUBLIC" already exists. Skipping schema creation.
09:38:20.656 [main] DEBUG c.g.f.c.m.MetaDataTableTo20FormatUpgrader - No upgrade to the Flyway 2.0 format necessary for metadata table "PUBLIC"."schema_version"
09:38:20.672 [main] DEBUG c.g.f.c.m.MetaDataTableTo202FormatUpgrader - No metadata table upgrade to the Flyway 2.0.2 format necessary
09:38:20.672 [main] DEBUG c.g.f.c.m.MetaDataTableTo21FormatUpgrader - No metadata table upgrade to the Flyway 2.1 format necessary
09:38:20.687 [main] DEBUG c.g.flyway.core.dbsupport.Table - Locking table "PUBLIC"."schema_version"...
09:38:20.687 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock acquired for table "PUBLIC"."schema_version"
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classpath resources at 'db/migration/hsqldb' (Prefix: 'V', Suffix: '.sql')
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V0__initial.sql
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V1__auth-server-admin.sql
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_1__add_resowner.sql
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Found resource: db/migration/hsqldb/V2_2__insert_resowner.sql
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.jdbc.JdbcMigration')
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.api.migration.spring.SpringJdbcMigration')
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning for classes at 'db/migration/hsqldb' (Implementing: 'com.googlecode.flyway.core.migration.java.JavaMigration')
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Scanning URL: file:/E:/github/apis/apis-authorization-server/target/test-classes/db/migration/hsqldb
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning starting at classpath root in filesystem: /E:/github/apis/apis-authorization-server/target/test-classes/
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.FileSystemClassPathLocationScanner - Scanning for resources in path: E:\github\apis\apis-authorization-server\target\test-classes\db\migration\hsqldb (db/migration/hsqldb)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V0__initial.sql (filename: V0__initial.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V1__auth-server-admin.sql (filename: V1__auth-server-admin.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_1__add_resowner.sql (filename: V2_1__add_resowner.sql)
09:38:20.687 [main] DEBUG c.g.f.c.u.s.c.ClassPathScanner - Filtering out resource: db/migration/hsqldb/V2_2__insert_resowner.sql (filename: V2_2__insert_resowner.sql)
09:38:20.687 [main] INFO c.g.flyway.core.command.DbMigrate - Current version of schema "PUBLIC": 2.2
09:38:20.703 [main] DEBUG c.g.flyway.core.dbsupport.Table - Unlocking table "PUBLIC"."schema_version"...
09:38:20.703 [main] DEBUG c.g.flyway.core.dbsupport.Table - Lock released for table "PUBLIC"."schema_version"
09:38:20.703 [main] INFO c.g.flyway.core.command.DbMigrate - Schema "PUBLIC" is up to date. No migration necessary.
09:38:20.718 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AccessToken_" for entity class org.surfnet.oaaas.model.AccessToken can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AccessToken_"
09:38:20.718 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceOwner_" for entity class org.surfnet.oaaas.model.ResourceOwner can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceOwner_"
09:38:20.718 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AbstractEntity_" for entity class org.surfnet.oaaas.model.AbstractEntity can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AbstractEntity_"
09:38:20.734 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.ResourceServer_" for entity class org.surfnet.oaaas.model.ResourceServer can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.ResourceServer_"
09:38:20.734 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.AuthorizationRequest_" for entity class org.surfnet.oaaas.model.AuthorizationRequest can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.AuthorizationRequest_"
09:38:20.734 [main] WARN openjpa.MetaData - Meta class "org.surfnet.oaaas.model.Client_" for entity class org.surfnet.oaaas.model.Client can not be registered with following exception "java.security.PrivilegedActionException: java.lang.ClassNotFoundException: org.surfnet.oaaas.model.Client_"
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByOwner
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByOwner
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByOwner.count
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndOwner
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByIdAndOwner
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByIdAndOwner.count
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByKey
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByKey
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByKey.count
09:38:20.734 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@3e322eb3]
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceServer
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByIdAndResourceServer
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByIdAndResourceServer.count
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceServer
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByResourceServer
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByResourceServer.count
09:38:20.734 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByClientId
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByClientId
09:38:20.734 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByClientId.count
09:38:20.734 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@57a131a4]
09:38:20.734 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@57ead1aa; candidate class: class org.surfnet.oaaas.model.ResourceServer; query: null] with parameters: ?
09:38:20.734 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@57ead1aa; candidate class: class org.surfnet.oaaas.model.ResourceServer; query: null] with parameters: ?
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1732011089 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl FROM resourceserver t0 WHERE (t0.resourceServerKey = ?) [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1732011089 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl FROM resourceserver t0 WHERE (t0.resourceServerKey = ?) [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1629230722 SELECT t0.id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.resourceServerKey = ?) ORDER BY t0.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1629230722 SELECT t0.id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE (t0.resourceServerKey = ?) ORDER BY t0.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1243471481 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_redirectUris t2 ON t1.id = t2.CLIENT_ID WHERE (t0.resourceServerKey = ?) ORDER BY t1.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1243471481 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_redirectUris t2 ON t1.id = t2.CLIENT_ID WHERE (t0.resourceServerKey = ?) ORDER BY t1.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1725925872 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_scopes t2 ON t1.id = t2.CLIENT_ID WHERE (t0.resourceServerKey = ?) ORDER BY t1.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1725925872 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_scopes t2 ON t1.id = t2.CLIENT_ID WHERE (t0.resourceServerKey = ?) ORDER BY t1.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1896000960 SELECT t0.id, t1.element FROM resourceserver t0 INNER JOIN ResourceServer_scopes t1 ON t0.id = t1.RESOURCESERVER_ID WHERE (t0.resourceServerKey = ?) ORDER BY t0.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1896000960 SELECT t0.id, t1.element FROM resourceserver t0 INNER JOIN ResourceServer_scopes t1 ON t0.id = t1.RESOURCESERVER_ID WHERE (t0.resourceServerKey = ?) ORDER BY t0.id ASC [params=?]
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.734 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 925536450 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 925536450 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@2f0d2baf; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.750 [main] TRACE openjpa.Query - Executing query: [Query: org.apache.openjpa.kernel.QueryImpl@2f0d2baf; candidate class: class org.surfnet.oaaas.model.Client; query: null] with parameters: ?
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 959053944 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 959053944 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE (t0.clientId = ?) [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 769953104 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 769953104 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 478858631 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 478858631 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE (t0.clientId = ?) ORDER BY t0.id ASC [params=?]
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByOwner
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByOwner
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByOwner.count
09:38:20.750 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndOwner
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByIdAndOwner
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByIdAndOwner.count
09:38:20.750 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByKey
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByKey
09:38:20.750 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByKey.count
09:38:20.750 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@67fe5cb7]
09:38:20.750 [main] TRACE openjpa.Query - Executing query: Query: org.apache.openjpa.kernel.QueryImpl@61e9704; candidate class: class org.surfnet.oaaas.model.ResourceServer; query: null
09:38:20.750 [main] TRACE openjpa.Query - Executing query: Query: org.apache.openjpa.kernel.QueryImpl@61e9704; candidate class: class org.surfnet.oaaas.model.ResourceServer; query: null
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 791225242 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl FROM resourceserver t0
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 791225242 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl FROM resourceserver t0
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1341024269 SELECT t0.id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id ORDER BY t0.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1341024269 SELECT t0.id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id ORDER BY t0.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1909965823 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_redirectUris t2 ON t1.id = t2.CLIENT_ID ORDER BY t1.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1909965823 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_redirectUris t2 ON t1.id = t2.CLIENT_ID ORDER BY t1.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1477961362 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_scopes t2 ON t1.id = t2.CLIENT_ID ORDER BY t1.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1477961362 SELECT DISTINCT t1.id, t2.element FROM resourceserver t0 INNER JOIN client t1 ON t0.id = t1.resourceserver_id INNER JOIN Client_scopes t2 ON t1.id = t2.CLIENT_ID ORDER BY t1.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 270196689 SELECT t0.id, t1.element FROM resourceserver t0 INNER JOIN ResourceServer_scopes t1 ON t0.id = t1.RESOURCESERVER_ID ORDER BY t0.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 270196689 SELECT t0.id, t1.element FROM resourceserver t0 INNER JOIN ResourceServer_scopes t1 ON t0.id = t1.RESOURCESERVER_ID ORDER BY t0.id ASC
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.750 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1792433683 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1792433683 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 118389053 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 118389053 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 369332999 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 369332999 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1517525514 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1517525514 SELECT t0.id, t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id WHERE t0.resourceserver_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2029701484 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2029701484 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_scopes t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1669791111 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1669791111 SELECT t0.id, t1.element FROM client t0 INNER JOIN Client_redirectUris t1 ON t0.id = t1.CLIENT_ID WHERE t0.resourceserver_id = ? ORDER BY t0.id ASC [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 913877753 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 913877753 SELECT t0.element FROM ResourceServer_scopes t0 WHERE t0.RESOURCESERVER_ID = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 564502171 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 564502171 SELECT t0.attribute_name, t0.attribute_value FROM client_attributes t0 WHERE t0.client_id = ? [params=?]
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.765 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByOwner
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByOwner
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByOwner.count
09:38:20.765 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndOwner
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByIdAndOwner
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByIdAndOwner.count
09:38:20.765 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByKey
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query ResourceServer.findByKey
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query ResourceServer.findByKey.count
09:38:20.765 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@29bb8b53]
09:38:20.765 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByIdAndResourceServer
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByIdAndResourceServer
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByIdAndResourceServer.count
09:38:20.765 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByResourceServer
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByResourceServer
09:38:20.765 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByResourceServer.count
09:38:20.781 [main] DEBUG o.s.d.j.r.query.SimpleJpaQuery - Looking up query for method findByClientId
09:38:20.781 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Looking up named query Client.findByClientId
09:38:20.781 [main] DEBUG o.s.d.j.repository.query.NamedQuery - Did not find named query Client.findByClientId.count
09:38:20.781 [main] DEBUG o.s.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is SingletonTargetSource for target object [org.springframework.data.jpa.repository.support.SimpleJpaRepository@47cdebc0]
09:38:20.781 [main] DEBUG o.h.v.i.m.d.ConstraintDescriptorImpl - Adding composing constraint: ConstraintDescriptorImpl{annotation=javax.validation.constraints.Pattern, payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message={javax.validation.constraints.Pattern.message}, payload=[Ljava.lang.Class;@3e848c55, flags=[Ljavax.validation.constraints.Pattern$Flag;@c7a5487, regexp=.*, groups=[Ljava.lang.Class;@70194083}}.
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2017141228 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2017141228 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1081279907 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1081279907 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 709589794 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 709589794 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2129713169 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 2129713169 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 526670902 INSERT INTO resourceserver (id, creationDate, modificationDate, contactEmail, contactName, description, resourceServerKey, resourceServerName, owner, secret, thumbNailUrl) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 526670902 INSERT INTO resourceserver (id, creationDate, modificationDate, contactEmail, contactName, description, resourceServerKey, resourceServerName, owner, secret, thumbNailUrl) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.781 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1977740627 INSERT INTO client (id, creationDate, modificationDate, allowedClientCredentials, allowedImplicitGrant, clientId, contactEmail, contactName, description, expireDuration, includePrincipal, clientName, secret, skipConsent, thumbNailUrl, useRefreshTokens, resourceserver_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1977740627 INSERT INTO client (id, creationDate, modificationDate, allowedClientCredentials, allowedImplicitGrant, clientId, contactEmail, contactName, description, expireDuration, includePrincipal, clientName, secret, skipConsent, thumbNailUrl, useRefreshTokens, resourceserver_id) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1134829723 INSERT INTO ResourceServer_scopes (RESOURCESERVER_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1134829723 INSERT INTO ResourceServer_scopes (RESOURCESERVER_ID, element) VALUES (?, ?) [params=?, ?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1651441032 DELETE FROM ResourceServer_scopes WHERE RESOURCESERVER_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1651441032 DELETE FROM ResourceServer_scopes WHERE RESOURCESERVER_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1284512668 DELETE FROM client_attributes WHERE client_id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1284512668 DELETE FROM client_attributes WHERE client_id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 409304447 DELETE FROM Client_redirectUris WHERE CLIENT_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 409304447 DELETE FROM Client_redirectUris WHERE CLIENT_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1364815656 DELETE FROM Client_scopes WHERE CLIENT_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1364815656 DELETE FROM Client_scopes WHERE CLIENT_ID = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1317276040 DELETE FROM resourceserver WHERE id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1317276040 DELETE FROM resourceserver WHERE id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1450816288 DELETE FROM client WHERE id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1450816288 DELETE FROM client WHERE id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1906727097 SELECT t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl, t1.resourceserver_id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 LEFT OUTER JOIN client t1 ON t0.id = t1.resourceserver_id LEFT OUTER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1906727097 SELECT t0.creationDate, t0.modificationDate, t0.contactEmail, t0.contactName, t0.description, t0.resourceServerKey, t0.resourceServerName, t0.owner, t0.secret, t0.thumbNailUrl, t1.resourceserver_id, t1.id, t1.creationDate, t1.modificationDate, t1.allowedClientCredentials, t1.allowedImplicitGrant, t1.clientId, t1.contactEmail, t1.contactName, t1.description, t1.expireDuration, t1.includePrincipal, t1.clientName, t2.id, t2.creationDate, t2.modificationDate, t2.contactEmail, t2.contactName, t2.description, t2.resourceServerKey, t2.resourceServerName, t2.owner, t2.secret, t2.thumbNailUrl, t1.secret, t1.skipConsent, t1.thumbNailUrl, t1.useRefreshTokens FROM resourceserver t0 LEFT OUTER JOIN client t1 ON t0.id = t1.resourceserver_id LEFT OUTER JOIN resourceserver t2 ON t1.resourceserver_id = t2.id WHERE t0.id = ? [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1247828628 SELECT t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens, t2.CLIENT_ID, t2.element FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id LEFT OUTER JOIN Client_redirectUris t2 ON t0.id = t2.CLIENT_ID WHERE t0.id = ? ORDER BY t2.CLIENT_ID ASC [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> executing prepstmnt 1247828628 SELECT t0.creationDate, t0.modificationDate, t0.allowedClientCredentials, t0.allowedImplicitGrant, t0.clientId, t0.contactEmail, t0.contactName, t0.description, t0.expireDuration, t0.includePrincipal, t0.clientName, t1.id, t1.creationDate, t1.modificationDate, t1.contactEmail, t1.contactName, t1.description, t1.resourceServerKey, t1.resourceServerName, t1.owner, t1.secret, t1.thumbNailUrl, t0.secret, t0.skipConsent, t0.thumbNailUrl, t0.useRefreshTokens, t2.CLIENT_ID, t2.element FROM client t0 INNER JOIN resourceserver t1 ON t0.resourceserver_id = t1.id LEFT OUTER JOIN Client_redirectUris t2 ON t0.id = t2.CLIENT_ID WHERE t0.id = ? ORDER BY t2.CLIENT_ID ASC [params=?]
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
09:38:20.796 [main] TRACE openjpa.jdbc.SQL - <t 585495085, conn 769537738> [0 ms] spent
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.171 sec
Running org.surfnet.oaaas.resource.resourceserver.AccessTokenResourceTest
09:38:20.828 [main] DEBUG o.s.o.r.r.AccessTokenResource - About to return all resource servers (0) for owner user
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.047 sec
Running org.surfnet.oaaas.resource.resourceserver.ClientResourceTest
09:38:20.859 [main] DEBUG o.s.o.r.r.AbstractResource - Resource required scopes ([write]) which the client has not been granted ([])
09:38:20.859 [main] DEBUG o.s.o.r.r.ClientResourceTest - client id generated: myname6
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 sec
Running org.surfnet.oaaas.resource.resourceserver.ResourceOwnerResourceTest
09:38:20.874 [main] DEBUG o.s.o.r.r.ResourceOwnerResource - About to return all resource owners (0) for owner user
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec
Running org.surfnet.oaaas.resource.resourceserver.ResourceServerResourceTest
09:38:20.874 [main] INFO o.s.o.r.r.ResourceServerResource - Resource server has updated scopes. Will remove all outdated scopes from clients: [scope1]
09:38:20.874 [main] INFO o.s.o.r.r.ResourceServerResource - Client scopes of client null were: [scope1]. After pruning (because resourceServer has new scopes): []
09:38:20.874 [main] INFO o.s.o.r.r.ResourceServerResource - Client scopes of client null were: [scope1, scope2]. After pruning (because resourceServer has new scopes): [scope2]
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Running org.surfnet.oaaas.resource.TokenResourceTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 sec
Results :
Tests run: 47, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ apis-authorization-server ---
[INFO] Building jar: E:\github\apis\apis-authorization-server\target\apis-authorization-server-1.3.6-SNAPSHOT.jar
[INFO]
[INFO] >>> maven-source-plugin:2.1.2:jar (attach-sources) @ apis-authorization-server >>>
[INFO]
[INFO] <<< maven-source-plugin:2.1.2:jar (attach-sources) @ apis-authorization-server <<<
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar (attach-sources) @ apis-authorization-server ---
[INFO] Building jar: E:\github\apis\apis-authorization-server\target\apis-authorization-server-1.3.6-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ apis-authorization-server ---
[INFO] Installing E:\github\apis\apis-authorization-server\target\apis-authorization-server-1.3.6-SNAPSHOT.jar to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-authorization-server\1.3.6-SNAPSHOT\apis-authorization-server-1.3.6-SNAPSHOT.jar
[INFO] Installing E:\github\apis\apis-authorization-server\pom.xml to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-authorization-server\1.3.6-SNAPSHOT\apis-authorization-server-1.3.6-SNAPSHOT.pom
[INFO] Installing E:\github\apis\apis-authorization-server\target\apis-authorization-server-1.3.6-SNAPSHOT-sources.jar to C:\Users\cgerlach\.m2\repository\nl\surfnet\apis\apis-authorization-server\1.3.6-SNAPSHOT\apis-authorization-server-1.3.6-SNAPSHOT-sources.jar
[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building API Secure - conext authentication plugin 1.3.6-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ apis-surfconext-authn ---
[INFO] Deleting E:\github\apis\apis-surfconext-authn\target
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ apis-surfconext-authn ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory E:\github\apis\apis-surfconext-authn\src\main\resources
[INFO]
[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ apis-surfconext-authn ---
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 5 source files to E:\github\apis\apis-surfconext-authn\target\classes
[INFO] -------------------------------------------------------------
[ERROR] COMPILATION ERROR :
[INFO] -------------------------------------------------------------
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[39,39] package org.opensaml.ws.message.decoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[40,39] package org.opensaml.ws.message.encoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[41,33] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[42,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[43,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[44,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[45,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[46,35] package org.opensaml.xml.validation does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[34,31] package org.apache.velocity.app does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[41,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[42,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[43,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[44,32] package org.opensaml.ws.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[45,41] package org.opensaml.ws.security.provider does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[46,24] package org.opensaml.xml does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[47,30] package org.opensaml.xml.parse does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[48,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[114,13] cannot find symbol
symbol: class VelocityEngine
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[134,13] cannot find symbol
symbol: class ReplayCache
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[146,13] cannot find symbol
symbol: class CredentialResolver
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[167,13] cannot find symbol
symbol: class SecurityPolicyResolver
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[39,39] package org.opensaml.ws.message.decoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[40,39] package org.opensaml.ws.message.encoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[41,33] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[42,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[43,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[44,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[45,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[46,35] package org.opensaml.xml.validation does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[34,31] package org.apache.velocity.app does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[41,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[42,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[43,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[44,32] package org.opensaml.ws.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[45,41] package org.opensaml.ws.security.provider does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[46,24] package org.opensaml.xml does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[47,30] package org.opensaml.xml.parse does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[48,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[114,13] cannot find symbol
symbol: class VelocityEngine
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[134,13] cannot find symbol
symbol: class ReplayCache
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[146,13] cannot find symbol
symbol: class CredentialResolver
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[167,13] cannot find symbol
symbol: class SecurityPolicyResolver
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[215,14] cannot find symbol
symbol: class MessageDecodingException
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[217,39] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[221,64] cannot access org.opensaml.ws.message.MessageContext
class file for org.opensaml.ws.message.MessageContext not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[223,50] cannot access org.opensaml.xml.validation.ValidatingXMLObject
class file for org.opensaml.xml.validation.ValidatingXMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[227,41] cannot access org.opensaml.xml.signature.SignableXMLObject
class file for org.opensaml.xml.signature.SignableXMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[230,39] cannot access org.opensaml.xml.XMLObject
class file for org.opensaml.xml.XMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[232,14] cannot find symbol
symbol: class ValidationException
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[254,39] cannot access org.opensaml.xml.AbstractXMLObjectBuilder
class file for org.opensaml.xml.AbstractXMLObjectBuilder not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[261,5] cannot find symbol
symbol: class CriteriaSet
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[261,35] cannot find symbol
symbol: class CriteriaSet
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[262,25] cannot find symbol
symbol: class EntityIDCriteria
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[263,25] cannot find symbol
symbol: class UsageCriteria
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[263,39] cannot find symbol
symbol: variable UsageType
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[266,7] cannot find symbol
symbol: class Credential
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[270,14] cannot find symbol
symbol: class MessageEncodingException
location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[88,14] cannot find symbol
symbol: class ConfigurationException
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,16] cannot find symbol
symbol: class ReplayCache
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,32] cannot find symbol
symbol: class MapBasedStorageService
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,62] cannot find symbol
symbol: class ReplayCacheEntry
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[164,45] cannot access org.opensaml.ws.security.SecurityPolicyRule
class file for org.opensaml.ws.security.SecurityPolicyRule not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[168,16] cannot find symbol
symbol: class StaticSecurityPolicyResolver
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[172,11] cannot find symbol
symbol: class BasicParserPool
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[172,49] cannot find symbol
symbol: class BasicParserPool
location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[175,12] cannot access org.opensaml.xml.parse.ParserPool
class file for org.opensaml.xml.parse.ParserPool not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLProvisioner.java:[61,34] cannot access org.opensaml.xml.AttributeExtensibleXMLObject
class file for org.opensaml.xml.AttributeExtensibleXMLObject not found
[INFO] 67 errors
[INFO] -------------------------------------------------------------
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] API Secure ........................................ SUCCESS [0.405s]
[INFO] API Secure - resource server library .............. SUCCESS [4.984s]
[INFO] API Secure - authorization server ................. SUCCESS [5.763s]
[INFO] API Secure - conext authentication plugin ......... FAILURE [0.374s]
[INFO] API Secure - example resource server .............. SKIPPED
[INFO] API Secure - mock openconext group api ............ SKIPPED
[INFO] API Secure - authorization server webapp .......... SKIPPED
[INFO] API Secure - example resource server war .......... SKIPPED
[INFO] API Secure - example client app war ............... SKIPPED
[INFO] API Secure - authorization server webapp dist ..... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 11.776s
[INFO] Finished at: Mon Jan 05 09:38:21 EST 2015
[INFO] Final Memory: 31M/354M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-compiler-plugin:3.1:compile (default-compile) on project apis-surfconext-authn: Compilation failure: Compilation failure:
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[39,39] package org.opensaml.ws.message.decoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[40,39] package org.opensaml.ws.message.encoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[41,33] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[42,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[43,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[44,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[45,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[46,35] package org.opensaml.xml.validation does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[34,31] package org.apache.velocity.app does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[41,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[42,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[43,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[44,32] package org.opensaml.ws.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[45,41] package org.opensaml.ws.security.provider does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[46,24] package org.opensaml.xml does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[47,30] package org.opensaml.xml.parse does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[48,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[114,13] cannot find symbol
[ERROR] symbol: class VelocityEngine
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[134,13] cannot find symbol
[ERROR] symbol: class ReplayCache
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[146,13] cannot find symbol
[ERROR] symbol: class CredentialResolver
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[167,13] cannot find symbol
[ERROR] symbol: class SecurityPolicyResolver
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[39,39] package org.opensaml.ws.message.decoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[40,39] package org.opensaml.ws.message.encoder does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[41,33] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[42,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[43,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[44,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[45,42] package org.opensaml.xml.security.criteria does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[46,35] package org.opensaml.xml.validation does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[34,31] package org.apache.velocity.app does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[41,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[42,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[43,33] package org.opensaml.util.storage does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[44,32] package org.opensaml.ws.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[45,41] package org.opensaml.ws.security.provider does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[46,24] package org.opensaml.xml does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[47,30] package org.opensaml.xml.parse does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[48,44] package org.opensaml.xml.security.credential does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[114,13] cannot find symbol
[ERROR] symbol: class VelocityEngine
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[134,13] cannot find symbol
[ERROR] symbol: class ReplayCache
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[146,13] cannot find symbol
[ERROR] symbol: class CredentialResolver
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[167,13] cannot find symbol
[ERROR] symbol: class SecurityPolicyResolver
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[215,14] cannot find symbol
[ERROR] symbol: class MessageDecodingException
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[217,39] package org.opensaml.xml.security does not exist
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[221,64] cannot access org.opensaml.ws.message.MessageContext
[ERROR] class file for org.opensaml.ws.message.MessageContext not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[223,50] cannot access org.opensaml.xml.validation.ValidatingXMLObject
[ERROR] class file for org.opensaml.xml.validation.ValidatingXMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[227,41] cannot access org.opensaml.xml.signature.SignableXMLObject
[ERROR] class file for org.opensaml.xml.signature.SignableXMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[230,39] cannot access org.opensaml.xml.XMLObject
[ERROR] class file for org.opensaml.xml.XMLObject not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[232,14] cannot find symbol
[ERROR] symbol: class ValidationException
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[254,39] cannot access org.opensaml.xml.AbstractXMLObjectBuilder
[ERROR] class file for org.opensaml.xml.AbstractXMLObjectBuilder not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[261,5] cannot find symbol
[ERROR] symbol: class CriteriaSet
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[261,35] cannot find symbol
[ERROR] symbol: class CriteriaSet
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[262,25] cannot find symbol
[ERROR] symbol: class EntityIDCriteria
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[263,25] cannot find symbol
[ERROR] symbol: class UsageCriteria
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[263,39] cannot find symbol
[ERROR] symbol: variable UsageType
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[266,7] cannot find symbol
[ERROR] symbol: class Credential
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLAuthenticator.java:[270,14] cannot find symbol
[ERROR] symbol: class MessageEncodingException
[ERROR] location: class org.surfnet.oaaas.conext.SAMLAuthenticator
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[88,14] cannot find symbol
[ERROR] symbol: class ConfigurationException
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,16] cannot find symbol
[ERROR] symbol: class ReplayCache
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,32] cannot find symbol
[ERROR] symbol: class MapBasedStorageService
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[135,62] cannot find symbol
[ERROR] symbol: class ReplayCacheEntry
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[164,45] cannot access org.opensaml.ws.security.SecurityPolicyRule
[ERROR] class file for org.opensaml.ws.security.SecurityPolicyRule not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[168,16] cannot find symbol
[ERROR] symbol: class StaticSecurityPolicyResolver
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[172,11] cannot find symbol
[ERROR] symbol: class BasicParserPool
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[172,49] cannot find symbol
[ERROR] symbol: class BasicParserPool
[ERROR] location: class org.surfnet.oaaas.conext.OpenSAMLContext
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/OpenSAMLContext.java:[175,12] cannot access org.opensaml.xml.parse.ParserPool
[ERROR] class file for org.opensaml.xml.parse.ParserPool not found
[ERROR] /E:/github/apis/apis-surfconext-authn/src/main/java/org/surfnet/oaaas/conext/SAMLProvisioner.java:[61,34] cannot access org.opensaml.xml.AttributeExtensibleXMLObject
[ERROR] class file for org.opensaml.xml.AttributeExtensibleXMLObject not found
[ERROR] -> [Help 1]
[ERROR]
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR]
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :apis-surfconext-authn
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment