Console Output

Skipping 767 KB.. Full Log
rdbms.mapping.column.BigIntColumnMapping)
14:54:25.504 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.LocalRepository.aliases.[ELEMENT]] -> Column(s) ["localrepository_aliases"."element"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.504 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table/View "localrepository_aliases" has been initialised
14:54:25.505 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@18fbc813 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
14:54:25.505 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9" with isolation "serializable"
14:54:25.511 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repository" returned table type of TABLE
14:54:25.511 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "repository" in Catalog "", Schema ""
14:54:25.522 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 11 ms
14:54:25.522 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "repository" : 13 columns found
14:54:25.525 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "localrepository_aliases" returned table type of TABLE
14:54:25.525 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "localrepository_aliases" in Catalog "", Schema ""
14:54:25.528 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 3 ms
14:54:25.529 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "localrepository_aliases" : 2 columns found
14:54:25.543 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 3 index(es) for table "repository"
14:54:25.543 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "repository_n49" ON "repository" ("root_id_oid")
14:54:25.569 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 26 ms
14:54:25.599 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "repository"
14:54:25.599 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - ALTER TABLE "repository" ADD CONSTRAINT "repository_fk1" FOREIGN KEY ("root_id_oid") REFERENCES "repofile" ("id") 
14:54:25.614 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 14 ms
14:54:25.634 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repository"
14:54:25.651 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "localrepository_aliases"
14:54:25.651 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "localrepository_aliases_n49" ON "localrepository_aliases" ("id_oid")
14:54:25.680 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 29 ms
14:54:25.707 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "localrepository_aliases"
14:54:25.708 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - ALTER TABLE "localrepository_aliases" ADD CONSTRAINT "localrepository_aliases_fk1" FOREIGN KEY ("id_oid") REFERENCES "repository" ("id") 
14:54:25.721 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 13 ms
14:54:25.740 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "localrepository_aliases"
14:54:25.740 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9"
14:54:25.747 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9"
14:54:25.747 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@18fbc813 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:25.747 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@18fbc813 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@5764be9, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:25.750 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /META-INF/package.jdo
14:54:25.750 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /WEB-INF/package.jdo
14:54:25.751 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /package.jdo
14:54:25.752 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/package.jdo
14:54:25.752 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/package.jdo
14:54:25.753 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/package.jdo
14:54:25.754 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/package.jdo
14:54:25.755 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/persistence/package.jdo
14:54:25.756 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/persistence/RemoteRepositoryRequest.jdo
14:54:25.756 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" not found
14:54:25.757 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" has been specified with JDO annotations so using those.
14:54:25.758 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" : Populating Meta-Data
14:54:25.759 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /META-INF/package.orm
14:54:25.760 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /WEB-INF/package.orm
14:54:25.760 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /package.orm
14:54:25.761 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/package.orm
14:54:25.761 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/package.orm
14:54:25.762 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/package.orm
14:54:25.763 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/package.orm
14:54:25.763 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/persistence/package.orm
14:54:25.764 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/local/persistence/RemoteRepositoryRequest.orm
14:54:25.764 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" not found
14:54:25.765 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" : Initialising Meta-Data
14:54:25.767 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest [Table : "remoterepositoryrequest", InheritanceStrategy : new-table]
14:54:25.768 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Member co.codewizards.cloudstore.local.persistence.Entity.id uses IDENTITY strategy and rather than using memberType of long for the column type, using class java.lang.Long since the datastore requires that
14:54:25.768 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.id] -> Column(s) ["remoterepositoryrequest"."id"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.LongMapping" (org.datanucleus.store.rdbms.mapping.column.BigIntColumnMapping)
14:54:25.768 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table "remoterepositoryrequest" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest (inheritance strategy="new-table") 
14:54:25.769 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest.localPathPrefix] -> Column(s) ["remoterepositoryrequest"."localpathprefix"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.769 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Attempt to find JDBC driver 'typeInfo' for jdbc-type=LONGVARBINARY but sql-type=LONGVARBINARY is not found. Using default sql-type for this jdbc-type.
14:54:25.769 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest.publicKey] -> Column(s) ["remoterepositoryrequest"."publickey"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.ArrayMapping" (org.datanucleus.store.rdbms.mapping.column.LongVarBinaryColumnMapping)
14:54:25.770 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest.repositoryId] -> Column(s) ["remoterepositoryrequest"."repositoryid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.770 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table "remoterepositoryrequest" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.Entity (inheritance strategy="subclass-table") 
14:54:25.770 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.changed] -> Column(s) ["remoterepositoryrequest"."changed"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.column.TimestampColumnMapping)
14:54:25.770 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.created] -> Column(s) ["remoterepositoryrequest"."created"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.column.TimestampColumnMapping)
14:54:25.771 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table/View "remoterepositoryrequest" has been initialised
14:54:25.771 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@889e16a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
14:54:25.771 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758" with isolation "serializable"
14:54:25.778 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "remoterepositoryrequest" returned table type of TABLE
14:54:25.778 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "remoterepositoryrequest" in Catalog "", Schema ""
14:54:25.788 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 10 ms
14:54:25.789 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "remoterepositoryrequest" : 6 columns found
14:54:25.797 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "remoterepositoryrequest"
14:54:25.818 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "remoterepositoryrequest"
14:54:25.821 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "remoterepositoryrequest"
14:54:25.821 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "remoterepositoryrequest_repositoryid" ON "remoterepositoryrequest" ("repositoryid")
14:54:25.850 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 29 ms
14:54:25.851 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758"
14:54:25.857 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758"
14:54:25.857 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@889e16a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:25.858 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@889e16a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@49715758, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:25.859 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.jdo
14:54:25.861 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.jdo
14:54:25.862 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.jdo
14:54:25.875 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.jdo
14:54:25.876 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.jdo
14:54:25.877 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.jdo
14:54:25.878 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/package.jdo
14:54:25.878 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence/package.jdo
14:54:25.879 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence/FileInProgressMarker.jdo
14:54:25.879 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
14:54:25.880 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" has been specified with JDO annotations so using those.
14:54:25.881 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Populating Meta-Data
14:54:25.882 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.orm
14:54:25.883 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.orm
14:54:25.883 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.orm
14:54:25.884 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.orm
14:54:25.885 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.orm
14:54:25.886 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.orm
14:54:25.886 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/package.orm
14:54:25.887 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence/package.orm
14:54:25.889 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence/FileInProgressMarker.orm
14:54:25.889 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
14:54:25.889 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Initialising Meta-Data
14:54:25.890 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.FileInProgressMarker [Table : "fileinprogressmarker", InheritanceStrategy : new-table]
14:54:25.890 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Member co.codewizards.cloudstore.local.persistence.Entity.id uses IDENTITY strategy and rather than using memberType of long for the column type, using class java.lang.Long since the datastore requires that
14:54:25.891 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.id] -> Column(s) ["fileinprogressmarker"."id"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.LongMapping" (org.datanucleus.store.rdbms.mapping.column.BigIntColumnMapping)
14:54:25.891 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table "fileinprogressmarker" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.FileInProgressMarker (inheritance strategy="new-table") 
14:54:25.891 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.FileInProgressMarker.fromRepositoryId] -> Column(s) ["fileinprogressmarker"."fromrepositoryid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.891 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Attempt to find JDBC driver 'typeInfo' for jdbc-type=CLOB but sql-type=VARCHAR is not found. Using default sql-type for this jdbc-type.
14:54:25.891 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.FileInProgressMarker.path] -> Column(s) ["fileinprogressmarker"."path"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.ClobColumnMapping)
14:54:25.892 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.FileInProgressMarker.pathSha1] -> Column(s) ["fileinprogressmarker"."pathsha1"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.892 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.FileInProgressMarker.toRepositoryId] -> Column(s) ["fileinprogressmarker"."torepositoryid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:25.892 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table "fileinprogressmarker" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.Entity (inheritance strategy="subclass-table") 
14:54:25.893 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.changed] -> Column(s) ["fileinprogressmarker"."changed"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.column.TimestampColumnMapping)
14:54:25.893 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.created] -> Column(s) ["fileinprogressmarker"."created"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.column.TimestampColumnMapping)
14:54:25.893 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table/View "fileinprogressmarker" has been initialised
14:54:25.894 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@25fdde0a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
14:54:25.894 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb" with isolation "serializable"
14:54:25.901 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "fileinprogressmarker" returned table type of TABLE
14:54:25.901 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "fileinprogressmarker" in Catalog "", Schema ""
14:54:25.911 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 10 ms
14:54:25.912 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "fileinprogressmarker" : 7 columns found
14:54:25.920 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "fileinprogressmarker"
14:54:25.931 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid")
14:54:25.962 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 31 ms
14:54:25.988 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "fileinprogressmarker"
14:54:26.005 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "fileinprogressmarker"
14:54:26.005 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid_pathsha1" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid","pathsha1")
14:54:26.029 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 24 ms
14:54:26.029 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb"
14:54:26.034 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb"
14:54:26.035 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@25fdde0a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:26.035 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@25fdde0a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7eb92fbb, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:26.035 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.jdo
14:54:26.036 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.jdo
14:54:26.036 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.jdo
14:54:26.037 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.jdo
14:54:26.037 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.jdo
14:54:26.037 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.jdo
14:54:26.038 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/package.jdo
14:54:26.038 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/package.jdo
14:54:26.039 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/Symlink.jdo
14:54:26.039 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
14:54:26.039 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" has been specified with JDO annotations so using those.
14:54:26.040 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Populating Meta-Data
14:54:26.041 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.orm
14:54:26.041 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.orm
14:54:26.041 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.orm
14:54:26.042 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.orm
14:54:26.042 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.orm
14:54:26.043 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.orm
14:54:26.043 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/package.orm
14:54:26.043 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/package.orm
14:54:26.044 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/Symlink.orm
14:54:26.044 [qtp1495162836-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
14:54:26.044 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Initialising Meta-Data
14:54:26.045 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.Symlink [Table : "repofile", InheritanceStrategy : superclass-table]
14:54:26.045 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Table "repofile" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.Symlink (inheritance strategy="superclass-table") 
14:54:26.045 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Member co.codewizards.cloudstore.local.persistence.Symlink.target uses superclass-table yet the field is not marked as nullable  nor does it have a default value, so setting the column as nullable
14:54:26.045 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Symlink.target] -> Column(s) ["repofile"."target"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.column.VarCharColumnMapping)
14:54:26.046 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6210c5aa [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
14:54:26.046 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5" with isolation "serializable"
14:54:26.049 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repofile" returned table type of TABLE
14:54:26.050 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "repofile" in Catalog "", Schema ""
14:54:26.057 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 7 ms
14:54:26.057 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "repofile" : 13 columns found
14:54:26.063 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 6 index(es) for table "repofile"
14:54:26.075 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 foreign key(s) for table "repofile"
14:54:26.080 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repofile"
14:54:26.081 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5"
14:54:26.081 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5"
14:54:26.081 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6210c5aa [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:26.081 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6210c5aa [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@50411ff5, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
14:54:26.082 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.082 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@7c742a4e" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@52205ccd" with txn="org.datanucleus.TransactionImpl@4ff3307b"
14:54:26.082 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=1375567738-11, enlisted resources=[]]
14:54:26.082 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e (optimistic=false)
14:54:26.083 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
14:54:26.083 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
14:54:26.083 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.LocalRepository]
14:54:26.083 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
14:54:26.084 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.085 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 2 ms
14:54:26.086 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@413651c8 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
14:54:26.086 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7451597a, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=1375567738-11, enlisted resources=[]]
14:54:26.087 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
14:54:26.094 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "56ac44df-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d"
14:54:26.094 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
14:54:26.095 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.095 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
14:54:26.095 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
14:54:26.096 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 2 cache
14:54:26.096 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
14:54:26.097 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="1") added to Level 2 cache (fields="[0, 1, 2, 4, 5]", version="")
14:54:26.097 [qtp1495162836-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "HOLLOW"->"P_CLEAN"
14:54:26.097 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="1") enlisted in transactional cache
14:54:26.098 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" having fields "publicKey" fetched from table ""repository""
14:54:26.104 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "825444e2-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d"
14:54:26.104 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."publickey" FROM "repository" "a0" WHERE "a0"."id" = <1>
14:54:26.105 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.105 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@2d137fc8"
14:54:26.105 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="1") is having the following fields in Level 2 cache object updated : [3]
14:54:26.106 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" having fields "privateKey" fetched from table ""repository""
14:54:26.112 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "d4c584e4-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d"
14:54:26.112 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."privatekey" FROM "repository" "a0" WHERE "a0"."id" = <1>
14:54:26.113 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.113 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@13e56ae9"
14:54:26.113 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="1") is having the following fields in Level 2 cache object updated : [7]
14:54:26.113 [qtp1495162836-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]initPersistenceManagerFactory: repositoryId=70167739-b41f-49ac-bc9d-9fffef6afaba
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=1375567738-11, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7451597a]]
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@413651c8 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.114 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@413651c8 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@53973a4d, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@1e355873, lifecycle=HOLLOW]
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
14:54:26.115 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.115 [qtp1495162836-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2279 ms.
14:54:26.116 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@7c742a4e" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@52205ccd" with txn="org.datanucleus.TransactionImpl@4313dd25"
14:54:26.116 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=1375567738-12, enlisted resources=[]]
14:54:26.116 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e (optimistic=false)
14:54:26.117 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Single-String with "SELECT WHERE this.changed < :changed"
14:54:26.117 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
14:54:26.117 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
14:54:26.118 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [filter:DyadicExpression{PrimaryExpression{this.changed}  <  ParameterExpression{changed}}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest, changed type=java.util.Date]
14:54:26.118 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
14:54:26.118 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.119 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.120 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@21cc75d1 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7c6b7e38, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
14:54:26.120 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@b9ff4ed, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=1375567738-12, enlisted resources=[]]
14:54:26.120 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" ...
14:54:26.127 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "e737c4e6-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7c6b7e38"
14:54:26.127 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS "dn_type","a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0" WHERE "a0"."changed" < <2020-04-13 14:54:26.116>
14:54:26.128 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=1375567738-12, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@b9ff4ed]]
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@21cc75d1 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7c6b7e38, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@21cc75d1 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7c6b7e38, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.129 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committed in 0 ms
14:54:26.130 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.130 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [7f63e30e]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
14:54:26.130 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [7f63e30e]tryAcquire: returning true. lockCounter=1
14:54:26.130 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [7f63e30e]tryAcquire: returning true. lockCounter=2
14:54:26.130 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
14:54:26.131 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [7f63e30e]release: NOT releasing underlying FileLock. lockCounter=1
14:54:26.131 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [7f63e30e]release: releasing underlying FileLock. lockCounter=0
14:54:26.131 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@7c742a4e" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@52205ccd" with txn="org.datanucleus.TransactionImpl@48a52299"
14:54:26.131 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=1375567738-13, enlisted resources=[]]
14:54:26.131 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e (optimistic=false)
14:54:26.132 [qtp1495162836-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" has been run before so reusing existing generic compilation
14:54:26.132 [qtp1495162836-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" for datastore "rdbms-derby" has been run before so reusing existing datastore compilation
14:54:26.132 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3fa5e0b7 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@61dd8200, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
14:54:26.132 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@38b0635c, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=1375567738-13, enlisted resources=[]]
14:54:26.133 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
14:54:26.133 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "56ac44df-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@61dd8200"
14:54:26.133 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
14:54:26.133 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
14:54:26.133 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 0 ms
14:54:26.134 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
14:54:26.134 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
14:54:26.134 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
14:54:26.134 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
14:54:26.135 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="1") enlisted in transactional cache
14:54:26.135 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" taken from Level 2 cache (fields="[0, 1, 2, 3, 4, 5, 7]", version="") - represented as "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427"
14:54:26.136 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, allow-nulls=false]
14:54:26.136 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="1") is having the following fields in Level 2 cache object updated : [6]
14:54:26.136 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" field "aliases" loading contents to SCO wrapper from the datastore
14:54:26.146 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "02e504e9-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@61dd8200"
14:54:26.146 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
14:54:26.147 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.147 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@318fc77a"
14:54:26.147 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [76f49a80]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
14:54:26.147 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [76f49a80]tryAcquire: returning true. lockCounter=1
14:54:26.148 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
14:54:26.148 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [76f49a80]release: releasing underlying FileLock. lockCounter=0
14:54:26.148 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.local.LocalRepoManagerImpl - syncWithLocalRepoRegistry: Alias 'k8zwqged-15sp8' of repository '70167739-b41f-49ac-bc9d-9fffef6afaba' already in-sync.
14:54:26.148 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [3fb08aa9]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
14:54:26.149 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [3fb08aa9]tryAcquire: returning true. lockCounter=1
14:54:26.149 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [3fb08aa9]tryAcquire: returning true. lockCounter=2
14:54:26.149 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
14:54:26.149 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [3fb08aa9]release: NOT releasing underlying FileLock. lockCounter=1
14:54:26.149 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [3fb08aa9]release: releasing underlying FileLock. lockCounter=0
14:54:26.150 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.150 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.150 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
14:54:26.150 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.150 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.150 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=1375567738-13, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@38b0635c]]
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3fa5e0b7 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@61dd8200, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3fa5e0b7 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@61dd8200, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
14:54:26.151 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
14:54:26.152 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@1510a427, lifecycle=HOLLOW]
14:54:26.152 [qtp1495162836-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
14:54:26.152 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.153 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@7c742a4e" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@52205ccd" with txn="org.datanucleus.TransactionImpl@7e607681"
14:54:26.153 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=1375567738-14, enlisted resources=[]]
14:54:26.153 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e (optimistic=false)
14:54:26.153 [qtp1495162836-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" has been run before so reusing existing generic compilation
14:54:26.153 [qtp1495162836-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" for datastore "rdbms-derby" has been run before so reusing existing datastore compilation
14:54:26.154 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@33658195 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
14:54:26.154 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@1799f384, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=1375567738-14, enlisted resources=[]]
14:54:26.154 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
14:54:26.154 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "56ac44df-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558"
14:54:26.154 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
14:54:26.155 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.155 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
14:54:26.155 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
14:54:26.155 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
14:54:26.156 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
14:54:26.156 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
14:54:26.156 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="1") enlisted in transactional cache
14:54:26.156 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" taken from Level 2 cache (fields="[0, 1, 2, 3, 4, 5, 7]", version="") - represented as "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1"
14:54:26.157 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, allow-nulls=false]
14:54:26.157 [qtp1495162836-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="1") is having the following fields in Level 2 cache object updated : [6]
14:54:26.163 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "9e9484ec-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558"
14:54:26.163 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT(*) FROM "localrepository_aliases" THIS WHERE THIS."id_oid"=<1>
14:54:26.164 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.165 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@6944e58e"
14:54:26.165 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" field "aliases" loading contents to SCO wrapper from the datastore
14:54:26.165 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "02e504e9-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558"
14:54:26.166 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
14:54:26.166 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
14:54:26.166 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@63a46d75"
14:54:26.166 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.166 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.166 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=1375567738-14, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@1799f384]]
14:54:26.167 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@33658195 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.168 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@33658195 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@15d7a558, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.168 [qtp1495162836-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
14:54:26.168 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
14:54:26.168 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
14:54:26.169 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@13ad9ed1, lifecycle=HOLLOW]
14:54:26.169 [qtp1495162836-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
14:54:26.169 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.169 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]open: closing=false closeAbortable=true
14:54:26.169 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@7c742a4e" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@52205ccd" with txn="org.datanucleus.TransactionImpl@3ec2aa0"
14:54:26.170 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=1375567738-15, enlisted resources=[]]
14:54:26.170 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e (optimistic=false)
14:54:26.170 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [14d5e5a4]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
14:54:26.170 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [14d5e5a4]tryAcquire: returning true. lockCounter=1
14:54:26.170 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [14d5e5a4]tryAcquire: returning true. lockCounter=2
14:54:26.171 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
14:54:26.171 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [14d5e5a4]release: NOT releasing underlying FileLock. lockCounter=1
14:54:26.171 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [14d5e5a4]release: releasing underlying FileLock. lockCounter=0
14:54:26.172 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
14:54:26.172 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
14:54:26.172 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepository]
14:54:26.173 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
14:54:26.173 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.174 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.175 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@75e0accb [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
14:54:26.175 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7332d411, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=1375567738-15, enlisted resources=[]]
14:54:26.175 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" ...
14:54:26.181 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "6a4644ef-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.182 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."localpathprefix","a0"."localrevision","a0"."remoteroot","a0"."remoterootsha1","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'RemoteRepository'
14:54:26.182 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
14:54:26.183 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 8 ms
14:54:26.183 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
14:54:26.183 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
14:54:26.183 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest]
14:54:26.184 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
14:54:26.184 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.185 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.185 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" ...
14:54:26.190 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "8cbe04f1-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.190 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS "dn_type","a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0"
14:54:26.191 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.191 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 6 ms
14:54:26.192 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
14:54:26.193 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
14:54:26.193 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.NormalFile]
14:54:26.193 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
14:54:26.194 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.194 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.195 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" ...
14:54:26.211 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "af36c4f3-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.213 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'
14:54:26.214 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.214 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 19 ms
14:54:26.214 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
14:54:26.215 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
14:54:26.216 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.Directory]
14:54:26.216 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
14:54:26.217 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.217 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.218 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" ...
14:54:26.225 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "51b084f5-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.226 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'
14:54:26.226 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
14:54:26.227 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
14:54:26.227 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
14:54:26.228 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
14:54:26.230 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.CopyModification]
14:54:26.231 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
14:54:26.232 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.232 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.232 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" ...
14:54:26.248 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "f42b44f7-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.249 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "copymodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'CopyModification'
14:54:26.249 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.249 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 17 ms
14:54:26.251 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
14:54:26.252 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
14:54:26.253 [qtp1495162836-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.DeleteModification]
14:54:26.253 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
14:54:26.254 [qtp1495162836-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
14:54:26.255 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
14:54:26.255 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" ...
14:54:26.267 [qtp1495162836-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "16a704f9-0171-78c0-f93a-000001354b30" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17"
14:54:26.267 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "deletemodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'DeleteModification'
14:54:26.268 [qtp1495162836-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
14:54:26.268 [qtp1495162836-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 13 ms
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.269 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@7c742a4e
14:54:26.269 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=1375567738-15, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7332d411]]
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@75e0accb [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@75e0accb [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@704edd17, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
14:54:26.270 [qtp1495162836-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@7c742a4e"
14:54:26.271 [qtp1495162836-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]getCloseDeferredMillis: closeDeferredMillis=0
14:54:26.271 [qtp1495162836-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]close: Closing real LocalRepoManager immediately.
14:54:26.271 [qtp1495162836-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [6583c1dd]_close: Shutting down real LocalRepoManager.
14:54:26.272 [qtp1495162836-38] DEBUG DataNucleus.Connection - Closing ConnectionPool "tx"
14:54:26.273 [qtp1495162836-38] DEBUG DataNucleus.Connection - Closing ConnectionPool "nontx"
14:54:26.274 [qtp1495162836-38] DEBUG DataNucleus.Persistence - Unmanaging Persistence of all classes
14:54:26.274 [qtp1495162836-38] DEBUG DataNucleus.MetaData - Deregistering listener for metadata initialisation
14:54:26.275 [qtp1495162836-38] DEBUG DataNucleus.Cache - Level 2 Cache cleared
14:54:26.615 [qtp1495162836-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [4ccbc94c]release: releasing underlying FileLock. lockCounter=0
14:54:26.621 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@28cdbd4[p=0,l=773,c=8192,r=773]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@ce2d3aa{null}
14:54:26.632 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /RepoInfo on HttpChannelOverHttp@77fdd132{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:39414/RepoInfo,age=2814}
200 OK HTTP/1.1
Content-Length: 773
Content-Type: application/xml


14:54:26.633 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@67791ef6[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@55705202] generate: NEED_HEADER (null,[p=0,l=773,c=8192,r=773],true)@START
14:54:26.633 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=200,h=2,cl=773} last=true content=HeapByteBuffer@28cdbd4[p=0,l=773,c=8192,r=773]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
14:54:26.633 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpGenerator - Content-Length: 773
Content-Type: application/xml


14:54:26.633 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
14:54:26.633 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@67791ef6[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@55705202] generate: FLUSH ([p=0,l=104,c=8192,r=104],[p=0,l=773,c=8192,r=773],true)@COMPLETING
14:54:26.634 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@31c66103{IDLE}->null [HeapByteBuffer@e5c1752[p=0,l=104,c=8192,r=104]={<<<HTTP/1.1 200 OK\r\n....v20190418)\r\n\r\n>>>RDdjRnTHBUd0JYZTl...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@28cdbd4[p=0,l=773,c=8192,r=773]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
14:54:26.634 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@31c66103{WRITING}->null:IDLE-->WRITING
14:54:26.636 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 877 SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=W,to=2832/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=END,244 of 244},g=HttpGenerator@1e51fcbc{s=COMPLETING}]=>HttpChannelOverHttp@77fdd132{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:39414/RepoInfo,age=2829}
14:54:26.637 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=877 remaining=0 WriteFlusher@31c66103{WRITING}->null
14:54:26.637 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@31c66103{IDLE}->null:WRITING-->IDLE
14:54:26.637 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@67791ef6[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@55705202] generate: DONE ([p=104,l=104,c=8192,r=0],[p=773,l=773,c=8192,r=0],true)@END
14:54:26.649 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.session - FinalSession=null, old_session_handler=null, this=org.eclipse.jetty.server.session.SessionHandler464831537==dftMaxIdleSec=-1, calling complete=true
14:54:26.649 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.session - Complete called with session null
14:54:26.649 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@77fdd132{r=1,c=true,c=false/true,a=DISPATCHED,uri=//127.0.0.1:39414/RepoInfo,age=2842}
14:54:26.649 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@73c24b15{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
14:54:26.650 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@77fdd132{r=1,c=true,c=false/true,a=COMPLETING,uri=//127.0.0.1:39414/RepoInfo,age=2843} action COMPLETE
14:54:26.650 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@73c24b15{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
14:54:26.650 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /RepoInfo written=773
14:54:26.650 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@73c24b15{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
14:54:26.650 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,244 of 244}
14:54:26.651 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
14:54:26.651 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
14:54:26.651 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@e5c1752[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
14:54:26.651 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@e5c1752[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
14:54:26.652 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=-,to=15/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@e5c1752[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
14:54:26.653 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=-,to=15/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@e5c1752[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
14:54:26.653 [qtp1495162836-38] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@e5c1752[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
14:54:26.654 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=-,to=16/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
14:54:26.654 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=-,to=17/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.655 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=-,flush=-,to=18/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.656 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@73cca05{AC.ReadCB@36c0db09{HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
14:54:26.656 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.657 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@6647b314 on ManagedSelector@5d3634c8{STARTED} id=1 keys=1 selected=0 updates=0
14:54:26.657 [qtp1495162836-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5d3634c8{STARTED} id=1 keys=1 selected=0 updates=1
14:54:26.657 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken with none selected
14:54:26.657 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken up from select, 0/0/1 selected
14:54:26.657 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 processing 0 keys, 1 updates
14:54:26.657 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.657 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@6647b314
14:54:26.658 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.658 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.658 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 waiting with 1 keys
14:54:26.658 [qtp1495162836-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=3/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@73c24b15{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
14:54:26.659 [qtp1495162836-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=4/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
14:54:26.674 [main] DEBUG co.codewizards.cloudstore.ls.rest.client.LocalServerRestClient - execute: invocation took 2944 ms
Local repository:
  repository.repositoryId = 70167739-b41f-49ac-bc9d-9fffef6afaba
  repository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/repo/k8zwqged-15sp8
  repository.aliases = co.codewizards.cloudstore.ls.core.dto.RepoInfoResponseDto@2b15ba1a
  repository.publicKeySha1 = DF:9C:0E:75:A1:91:00:93:C7:8D:62:C7:35:71:92:52:D2:D1:36:D2

Remote repositories connected: {NONE}

Remote repositories requesting connection: {NONE}

Statistics:
  * Count(NormalFile): 0
  * Count(Directory): 1
  * Count(CopyModification): 0
  * Count(DeleteModification): 0

14:54:26.676 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping Server@7bbd76b8{STARTED}[9.4.17.v20190418]
14:54:26.676 [main] DEBUG org.eclipse.jetty.server.Server - doStop Server@7bbd76b8{STOPPING}[9.4.17.v20190418]
14:54:26.677 [qtp1495162836-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran qtp1495162836-35-acceptor-0@73da303e-ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:39414}
14:54:26.677 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandlerContainer - Graceful shutdown Server@7bbd76b8{STOPPING}[9.4.17.v20190418] by 
14:54:26.677 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
14:54:26.678 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping SelectorManager@ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
14:54:26.678 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@1d1b3620{STARTED} id=2 keys=0 selected=0 updates=0
14:54:26.678 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@796fe2b5 on ManagedSelector@1d1b3620{STOPPING} id=2 keys=0 selected=0 updates=0
14:54:26.678 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1d1b3620{STOPPING} id=2 keys=0 selected=0 updates=1
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 woken with none selected
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 woken up from select, 0/0/0 selected
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 processing 0 keys, 1 updates
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@796fe2b5
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@1d1b3620{STOPPING} id=2 keys=0 selected=0 updates=0
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.679 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 waiting with 0 keys
14:54:26.680 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@5a6af2c4 on ManagedSelector@1d1b3620{STOPPING} id=2 keys=0 selected=0 updates=0
14:54:26.680 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1d1b3620{STOPPING} id=2 keys=0 selected=0 updates=1
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 woken with none selected
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 woken up from select, 0/0/0 selected
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@64dc3531 processing 0 keys, 1 updates
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@5a6af2c4
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.680 [qtp1495162836-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@1c815814
14:54:26.681 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@6e67a8c0/SelectorProducer@5960f17/IDLE/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-14T14:54:26.681392+02:00
14:54:26.682 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@6e67a8c0/SelectorProducer@5960f17/IDLE/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-14T14:54:26.681939+02:00
14:54:26.682 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@1d1b3620{STOPPED} id=2 keys=-1 selected=-1 updates=0
14:54:26.682 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@5d3634c8{STARTED} id=1 keys=1 selected=0 updates=0
14:54:26.682 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@123c48d0 on ManagedSelector@5d3634c8{STOPPING} id=1 keys=1 selected=0 updates=0
14:54:26.683 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5d3634c8{STOPPING} id=1 keys=1 selected=0 updates=1
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken with none selected
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken up from select, 0/0/1 selected
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 processing 0 keys, 1 updates
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@123c48d0
14:54:26.683 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 1 connections on ManagedSelector@5d3634c8{STOPPING} id=1 keys=1 selected=0 updates=0
14:54:26.684 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=28/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.684 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,OPEN,fill=FI,flush=-,to=29/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.685 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,CLOSED,fill=FI,flush=-,to=30/30000}{io=1/1,kio=1,kro=1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.685 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@31c66103{IDLE}->null
java.nio.channels.ClosedChannelException: null
	at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:492)
	at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353)
	at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:215)
	at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225)
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192)
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175)
	at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:248)
	at org.eclipse.jetty.io.ManagedSelector.closeNoExceptions(ManagedSelector.java:252)
	at org.eclipse.jetty.io.ManagedSelector.access$1400(ManagedSelector.java:61)
	at org.eclipse.jetty.io.ManagedSelector$CloseConnections.update(ManagedSelector.java:866)
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.processUpdates(ManagedSelector.java:426)
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:397)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:357)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:181)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:786)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:743)
	at java.base/java.lang.Thread.run(Thread.java:834)
14:54:26.686 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@73cca05{AC.ReadCB@36c0db09{HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,CLOSED,fill=FI,flush=-,to=31/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@36c0db09[p=HttpParser{s=START,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
14:54:26.686 [qtp1495162836-33] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
14:54:26.686 [qtp1495162836-33] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
14:54:26.687 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,CLOSED,fill=-,flush=-,to=31/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@36c0db09[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed {}
14:54:26.687 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@5d3634c8{STOPPING} id=1 keys=1 selected=0 updates=0
14:54:26.687 [qtp1495162836-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@69f90d9e
14:54:26.687 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.687 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 waiting with 1 keys
14:54:26.687 [qtp1495162836-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@69f90d9e
14:54:26.687 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@14fff5e7 on ManagedSelector@5d3634c8{STOPPING} id=1 keys=0 selected=0 updates=0
14:54:26.687 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@5d3634c8{STOPPING} id=1 keys=0 selected=0 updates=1
14:54:26.688 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken with none selected
14:54:26.688 [qtp1495162836-39] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,CLOSED,fill=-,flush=-,to=32/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@36c0db09[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.688 [qtp1495162836-39] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose HttpConnection@36c0db09::SocketChannelEndPoint@793a2065{/127.0.0.1:56070<->/127.0.0.1:39414,CLOSED,fill=-,flush=-,to=33/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@36c0db09[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@1e51fcbc{s=START}]=>HttpChannelOverHttp@77fdd132{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
14:54:26.688 [qtp1495162836-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@69f90d9e
14:54:26.689 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 woken up from select, 0/0/0 selected
14:54:26.689 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2603ed15 processing 0 keys, 1 updates
14:54:26.689 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.690 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@14fff5e7
14:54:26.690 [qtp1495162836-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.691 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@145fe8ab/SelectorProducer@3b347439/PRODUCING/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-04-14T14:54:26.690903+02:00
14:54:26.691 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@145fe8ab/SelectorProducer@3b347439/PRODUCING/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-04-14T14:54:26.691342+02:00
14:54:26.691 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@5d3634c8{STOPPED} id=1 keys=-1 selected=-1 updates=0
14:54:26.691 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@520c3dcb{STARTED} id=0 keys=0 selected=0 updates=0
14:54:26.691 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@1203d787 on ManagedSelector@520c3dcb{STOPPING} id=0 keys=0 selected=0 updates=0
14:54:26.692 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@520c3dcb{STOPPING} id=0 keys=0 selected=0 updates=1
14:54:26.692 [qtp1495162836-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@174e1b99
14:54:26.692 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 woken with none selected
14:54:26.692 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 woken up from select, 0/0/0 selected
14:54:26.692 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 processing 0 keys, 1 updates
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@1203d787
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@520c3dcb{STOPPING} id=0 keys=0 selected=0 updates=0
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 waiting with 0 keys
14:54:26.693 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@1f72fbd1 on ManagedSelector@520c3dcb{STOPPING} id=0 keys=0 selected=0 updates=0
14:54:26.693 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@520c3dcb{STOPPING} id=0 keys=0 selected=0 updates=1
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 woken with none selected
14:54:26.693 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 woken up from select, 0/0/0 selected
14:54:26.694 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@5406aaa1 processing 0 keys, 1 updates
14:54:26.694 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
14:54:26.694 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@1f72fbd1
14:54:26.694 [qtp1495162836-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
14:54:26.694 [qtp1495162836-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@71e7c13f
14:54:26.694 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@1dd64243/SelectorProducer@2ef913a4/IDLE/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-14T14:54:26.694815+02:00
14:54:26.695 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@1dd64243/SelectorProducer@2ef913a4/IDLE/p=false/QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-14T14:54:26.695242+02:00
14:54:26.695 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@520c3dcb{STOPPED} id=0 keys=-1 selected=-1 updates=0
14:54:26.695 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED SelectorManager@ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
14:54:26.695 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HttpConnectionFactory@37b238da[HTTP/1.1]
14:54:26.695 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HttpConnectionFactory@37b238da[HTTP/1.1]
14:54:26.695 [main] INFO org.eclipse.jetty.server.AbstractConnector - Stopped ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ServerConnector@ce19c86{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
14:54:26.696 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping Server@7bbd76b8{STOPPING}[9.4.17.v20190418]
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping DefaultSessionIdManager@369a95a5{STARTED}[worker=node0]
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HouseKeeper@543da15{STARTED}[interval=600000, ownscheduler=false]
14:54:26.696 [main] INFO org.eclipse.jetty.server.session - node0 Stopped scavenging
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HouseKeeper@543da15{STOPPED}[interval=600000, ownscheduler=false]
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED DefaultSessionIdManager@369a95a5{STOPPED}[worker=node0]
14:54:26.696 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ErrorHandler@158e6fc2{STARTED}
14:54:26.696 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping ErrorHandler@158e6fc2{STOPPING}
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ErrorHandler@158e6fc2{STOPPED}
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping o.e.j.s.ServletContextHandler@1b317e7d{/,null,SHUTDOWN}
14:54:26.697 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandlerContainer - Graceful shutdown o.e.j.s.ServletContextHandler@1b317e7d{/,null,SHUTDOWN} by 
14:54:26.697 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping o.e.j.s.ServletContextHandler@1b317e7d{/,null,UNAVAILABLE}
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.SessionHandler464831537==dftMaxIdleSec=-1
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.DefaultSessionCache@26495639[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.NullSessionDataStore@45c408a4[passivating=false,graceSec=3600]
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.NullSessionDataStore@45c408a4[passivating=false,graceSec=3600]
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.DefaultSessionCache@26495639[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
14:54:26.697 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.session.SessionHandler464831537==dftMaxIdleSec=-1
14:54:26.697 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ServletHandler@4fd3b20a{STARTED}
14:54:26.698 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping ServletHandler@4fd3b20a{STOPPING}
14:54:26.698 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-cb318ac@53b75e29==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
14:54:26.698 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-cb318ac@53b75e29==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
14:54:26.698 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.glassfish.jersey.servlet.ServletContainer-56b66a26@2d7736f2==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
14:54:26.710 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.glassfish.jersey.servlet.ServletContainer-56b66a26@2d7736f2==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
14:54:26.710 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ServletHandler@4fd3b20a{STOPPED}
14:54:26.710 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.SessionHandler464831537==dftMaxIdleSec=-1
14:54:26.710 [main] INFO org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.s.ServletContextHandler@1b317e7d{/,null,UNAVAILABLE}
14:54:26.711 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED o.e.j.s.ServletContextHandler@1b317e7d{/,null,UNAVAILABLE}
14:54:26.711 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@2248d140{STARTED}
14:54:26.713 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@2248d140{STOPPED}
14:54:26.713 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping QueuedThreadPool[qtp1495162836]@591e5fd4{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}]
14:54:26.713 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Stopping QueuedThreadPool[qtp1495162836]@591e5fd4{STOPPING,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@54687fd0{s=1/6,p=0}]
14:54:26.713 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ReservedThreadExecutor@54687fd0{s=1/6,p=0}
14:54:26.713 [main] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@54687fd0{s=0/6,p=0}@2cfb8665 offer STOP!
14:54:26.714 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ReservedThreadExecutor@54687fd0{s=0/6,p=0}
14:54:26.714 [qtp1495162836-37] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@54687fd0{s=0/6,p=0}@2cfb8665 task=STOP!
14:54:26.714 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[qtp1495162836-32,5,main] for 14999
14:54:26.714 [qtp1495162836-37] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@54687fd0{s=0/6,p=0}@2cfb8665 Exited
14:54:26.714 [qtp1495162836-36] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-37] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@54687fd0{s=0/6,p=0}@2cfb8665
14:54:26.714 [qtp1495162836-36] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.714 [qtp1495162836-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.715 [qtp1495162836-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x000000080094cc40@1dc965f5
14:54:26.716 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[qtp1495162836-39,5,] for 14998
14:54:26.716 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED QueuedThreadPool[qtp1495162836]@591e5fd4{STOPPED,8<=0<=500,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$67/0x00000008001e4840@5bf8fa12]
14:54:26.716 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED Server@7bbd76b8{STOPPED}[9.4.17.v20190418]
14:54:26.716 [main] INFO co.codewizards.cloudstore.ls.server.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/localServerRunning.deleteToStop
14:54:26.716 [main] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [749ad37c]release: releasing underlying FileLock. lockCounter=0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.457 sec

Results :

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0

[JENKINS] Recording test results
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-jar-plugin:2.4:jar[m [1m(default-jar)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-dependency-plugin:2.8:copy-dependencies[m [1m(copy-dependencies)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Copying jaxb-api-2.4.0-b180830.0359.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jaxb-api-2.4.0-b180830.0359.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.rest.shared-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.shared-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying hk2-utils-2.5.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/hk2-utils-2.5.0.jar
[[1;34mINFO[m] Copying javax.mail-1.6.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/javax.mail-1.6.0.jar
[[1;34mINFO[m] Copying jakarta.inject-2.6.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jakarta.inject-2.6.1.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.ls.rest.server-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.server-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jetty-util-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-util-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying jetty-servlet-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-servlet-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.local-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.local-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying hk2-locator-2.5.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/hk2-locator-2.5.0.jar
[[1;34mINFO[m] Copying aopalliance-repackaged-2.5.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/aopalliance-repackaged-2.5.0.jar
[[1;34mINFO[m] Copying FastInfoset-1.2.15.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/FastInfoset-1.2.15.jar
[[1;34mINFO[m] Copying jersey-client-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-client-2.29.1.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.ls.core-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.core-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jcl-over-slf4j-2.0.0-alpha1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jcl-over-slf4j-2.0.0-alpha1.jar
[[1;34mINFO[m] Copying jetty-continuation-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-continuation-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying datanucleus-core-5.2.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/datanucleus-core-5.2.2.jar
[[1;34mINFO[m] Copying bcprov-jdk15on-161b03.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/bcprov-jdk15on-161b03.jar
[[1;34mINFO[m] Copying javassist-3.22.0-CR2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/javassist-3.22.0-CR2.jar
[[1;34mINFO[m] Copying log4j-over-slf4j-2.0.0-alpha1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/log4j-over-slf4j-2.0.0-alpha1.jar
[[1;34mINFO[m] Copying jakarta.annotation-api-1.3.5.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jakarta.annotation-api-1.3.5.jar
[[1;34mINFO[m] Copying jakarta.ws.rs-api-2.1.6.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jakarta.ws.rs-api-2.1.6.jar
[[1;34mINFO[m] Copying osgi-resource-locator-1.0.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/osgi-resource-locator-1.0.3.jar
[[1;34mINFO[m] Copying jetty-http-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-http-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying slf4j-api-2.0.0-alpha1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/slf4j-api-2.0.0-alpha1.jar
[[1;34mINFO[m] Copying datanucleus-rdbms-5.2.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/datanucleus-rdbms-5.2.2.jar
[[1;34mINFO[m] Copying jetty-security-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-security-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.core.oio.nio-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core.oio.nio-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jersey-container-servlet-core-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-container-servlet-core-2.29.1.jar
[[1;34mINFO[m] Copying args4j-2.0.16.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/args4j-2.0.16.jar
[[1;34mINFO[m] Copying jaxb-runtime-2.4.0-b180830.0438.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jaxb-runtime-2.4.0-b180830.0438.jar
[[1;34mINFO[m] Copying javax.activation-api-1.2.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/javax.activation-api-1.2.0.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.core-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.rest.client-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.client-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying stax-ex-1.8.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/stax-ex-1.8.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.ls.rest.client-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.client-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jersey-media-jaxb-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-media-jaxb-2.29.1.jar
[[1;34mINFO[m] Copying istack-commons-runtime-3.0.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/istack-commons-runtime-3.0.7.jar
[[1;34mINFO[m] Copying derby-10.13.1.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/derby-10.13.1.1.jar
[[1;34mINFO[m] Copying postgresql-42.2.12.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/postgresql-42.2.12.jar
[[1;34mINFO[m] Copying jul-to-slf4j-2.0.0-alpha1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jul-to-slf4j-2.0.0-alpha1.jar
[[1;34mINFO[m] Copying jersey-server-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-server-2.29.1.jar
[[1;34mINFO[m] Copying jersey-hk2-2.28.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-hk2-2.28.jar
[[1;34mINFO[m] Copying commons-compress-1.18.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/commons-compress-1.18.jar
[[1;34mINFO[m] Copying jetty-servlets-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-servlets-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying jetty-server-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-server-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying jersey-container-servlet-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-container-servlet-2.29.1.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.ls.server-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jersey-common-2.29.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jersey-common-2.29.1.jar
[[1;34mINFO[m] Copying txw2-2.4.0-b180830.0438.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/txw2-2.4.0-b180830.0438.jar
[[1;34mINFO[m] Copying hk2-api-2.5.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/hk2-api-2.5.0.jar
[[1;34mINFO[m] Copying javax.jdo-3.2.0-m13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/javax.jdo-3.2.0-m13.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.updater-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.updater-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying jakarta.validation-api-2.0.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jakarta.validation-api-2.0.2.jar
[[1;34mINFO[m] Copying jetty-xml-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-xml-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying co.codewizards.cloudstore.ls.server.cproc-1.0.1-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server.cproc-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Copying logback-classic-1.3.0-alpha4.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/logback-classic-1.3.0-alpha4.jar
[[1;34mINFO[m] Copying datanucleus-api-jdo-5.2.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/datanucleus-api-jdo-5.2.3.jar
[[1;34mINFO[m] Copying jetty-io-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-io-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying logback-core-1.3.0-alpha4.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/logback-core-1.3.0-alpha4.jar
[[1;34mINFO[m] Copying jetty-webapp-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/jetty-webapp-9.4.17.v20190418.jar
[[1;34mINFO[m] Copying bcpg-jdk15on-161b03.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/bcpg-jdk15on-161b03.jar
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-assembly-plugin:2.2-beta-5:single[m [1m(make-assembly)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Reading assembly descriptor: src/assembly/bin.xml
[[1;34mINFO[m] Building tar : /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.tar.gz
[[1;34mINFO[m] Building zip: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.zip
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-source-plugin:3.2.1:jar-no-fork[m [1m(attach-sources)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-sources.jar
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-install-plugin:2.4:install[m [1m(default-install)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT.jar
[[1;34mINFO[m] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT.pom
[[1;34mINFO[m] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.tar.gz to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.tar.gz
[[1;34mINFO[m] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.zip to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-bin.zip
[[1;34mINFO[m] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-SNAPSHOT-sources.jar
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-deploy-plugin:2.7:deploy[m [1m(default-deploy)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Downloading from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml
[[1;34mINFO[m] Downloaded from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml (1.4 kB at 17 kB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10.jar
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10.jar (45 kB at 736 kB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10.pom
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10.pom (3.8 kB at 948 kB/s)
[[1;34mINFO[m] Downloading from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[[1;34mINFO[m] Downloaded from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (317 B at 106 kB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml (1.4 kB at 289 kB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (317 B at 317 kB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-bin.tar.gz
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-bin.tar.gz (24 MB at 57 MB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.4 MB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-bin.zip
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-bin.zip (24 MB at 54 MB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.4 MB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-sources.jar
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/co.codewizards.cloudstore.client-1.0.1-20200414.125435-10-sources.jar (29 kB at 14 MB/s)
[[1;34mINFO[m] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml
[[1;34mINFO[m] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.1-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.4 MB/s)
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-site-plugin:3.3:site[m [1m(default-site)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.client
[[1;34mINFO[m] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[[1;34mINFO[m] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.client/de
[[1;34mINFO[m] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore_java_11/site/co.codewizards.cloudstore.client
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m>>> [0;32mmaven-javadoc-plugin:3.1.1:javadoc[m [1m(javadoc-generate-unfiltered)[0;1m > [0;1mgenerate-sources[m @ [36mco.codewizards.cloudstore.client[0;1m >>>[m
[WARNING] Failed to getClass for org.apache.maven.plugins.javadoc.JavadocReport
[JENKINS] Archiving  javadoc
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m<<< [0;32mmaven-javadoc-plugin:3.1.1:javadoc[m [1m(javadoc-generate-unfiltered)[0;1m < [0;1mgenerate-sources[m @ [36mco.codewizards.cloudstore.client[0;1m <<<[m
[[1;34mINFO[m] 
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-javadoc-plugin:3.1.1:javadoc[m [1m(javadoc-generate-unfiltered)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Skipping javadoc generation
[JENKINS] Archiving  javadoc
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-resources-plugin:2.6:copy-resources[m [1m(javadoc-filter)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Using 'UTF-8' encoding to copy filtered resources.
[[1;34mINFO[m] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[[1;34mINFO[m] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-resources-plugin:2.6:copy-resources[m [1m(javadoc-copy-to-site)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
[[1;34mINFO[m] Using 'UTF-8' encoding to copy filtered resources.
[[1;34mINFO[m] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/apidocs
[[1;34mINFO[m] 
[[1;34mINFO[m] [1m--- [0;32mmaven-site-plugin:3.3:deploy[m [1m(default-deploy)[m @ [36mco.codewizards.cloudstore.client[0;1m ---[m
file:/tmp/cloudstore-junk/ - Session: Opened  
[[1;34mINFO[m] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/site
[[1;34mINFO[m]    >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.client
[[1;34mINFO[m]    >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.client
file:/tmp/cloudstore-junk/ - Session: Disconnecting  
file:/tmp/cloudstore-junk/ - Session: Disconnected