Console Output

345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 22 ms
11:45:15.547 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@16421fc6"
11:45:15.553 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@16421fc6"
11:45:15.554 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@412216ea [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@16421fc6, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.554 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@412216ea [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@16421fc6, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.557 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.LocalRepository [Table : "repository", InheritanceStrategy : superclass-table]
11:45:15.558 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Table "repository" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.LocalRepository (inheritance strategy="superclass-table") 
11:45:15.559 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Managing Persistence of Field : co.codewizards.cloudstore.local.persistence.LocalRepository.aliases [Table : "localrepository_aliases"]
11:45:15.559 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.LocalRepository.aliases] -> Column(s) [[none]] using mapping of type "org.datanucleus.store.rdbms.mapping.java.CollectionMapping" ()
11:45:15.561 [qtp1419345767-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.
11:45:15.562 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.LocalRepository.privateKey] -> Column(s) ["repository"."privatekey"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.ArrayMapping" (org.datanucleus.store.rdbms.mapping.column.LongVarBinaryColumnMapping)
11:45:15.564 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.LocalRepository.root] -> Column(s) ["repository"."root_id_oid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.PersistableMapping" (org.datanucleus.store.rdbms.mapping.column.BigIntColumnMapping)
11:45:15.564 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.LocalRepository.aliases.[OWNER]] -> Column(s) ["localrepository_aliases"."id_oid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.PersistableMapping" (org.datanucleus.store.rdbms.mapping.column.BigIntColumnMapping)
11:45:15.565 [qtp1419345767-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)
11:45:15.565 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Table/View "localrepository_aliases" has been initialised
11:45:15.565 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7f3451a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
11:45:15.565 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6" with isolation "serializable"
11:45:15.570 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repository" returned table type of TABLE
11:45:15.570 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "repository" in Catalog "", Schema ""
11:45:15.579 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 9 ms
11:45:15.579 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "repository" : 13 columns found
11:45:15.581 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "localrepository_aliases" returned table type of TABLE
11:45:15.581 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "localrepository_aliases" in Catalog "", Schema ""
11:45:15.583 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 2 ms
11:45:15.583 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "localrepository_aliases" : 2 columns found
11:45:15.591 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 3 index(es) for table "repository"
11:45:15.592 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "repository_n49" ON "repository" ("root_id_oid")
11:45:15.620 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 28 ms
11:45:15.655 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "repository"
11:45:15.656 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - ALTER TABLE "repository" ADD CONSTRAINT "repository_fk1" FOREIGN KEY ("root_id_oid") REFERENCES "repofile" ("id") 
11:45:15.668 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 12 ms
11:45:15.685 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repository"
11:45:15.698 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "localrepository_aliases"
11:45:15.699 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "localrepository_aliases_n49" ON "localrepository_aliases" ("id_oid")
11:45:15.721 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 22 ms
11:45:15.748 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "localrepository_aliases"
11:45:15.749 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - ALTER TABLE "localrepository_aliases" ADD CONSTRAINT "localrepository_aliases_fk1" FOREIGN KEY ("id_oid") REFERENCES "repository" ("id") 
11:45:15.760 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 11 ms
11:45:15.775 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "localrepository_aliases"
11:45:15.775 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6"
11:45:15.781 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6"
11:45:15.781 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7f3451a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.781 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7f3451a [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@e82cf6, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.783 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /META-INF/package.jdo
11:45:15.784 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /WEB-INF/package.jdo
11:45:15.785 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /package.jdo
11:45:15.786 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/package.jdo
11:45:15.787 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/package.jdo
11:45:15.789 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/package.jdo
11:45:15.790 [qtp1419345767-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
11:45:15.791 [qtp1419345767-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
11:45:15.792 [qtp1419345767-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
11:45:15.792 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" not found
11:45:15.793 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" has been specified with JDO annotations so using those.
11:45:15.794 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" : Populating Meta-Data
11:45:15.794 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /META-INF/package.orm
11:45:15.795 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /WEB-INF/package.orm
11:45:15.795 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /package.orm
11:45:15.796 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/package.orm
11:45:15.796 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/package.orm
11:45:15.797 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" NOT found at /co/codewizards/cloudstore/package.orm
11:45:15.798 [qtp1419345767-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
11:45:15.798 [qtp1419345767-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
11:45:15.799 [qtp1419345767-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
11:45:15.799 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" not found
11:45:15.799 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" : Initialising Meta-Data
11:45:15.802 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest [Table : "remoterepositoryrequest", InheritanceStrategy : new-table]
11:45:15.803 [qtp1419345767-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
11:45:15.803 [qtp1419345767-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)
11:45:15.803 [qtp1419345767-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") 
11:45:15.803 [qtp1419345767-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)
11:45:15.804 [qtp1419345767-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.
11:45:15.804 [qtp1419345767-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)
11:45:15.804 [qtp1419345767-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)
11:45:15.804 [qtp1419345767-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") 
11:45:15.804 [qtp1419345767-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)
11:45:15.805 [qtp1419345767-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)
11:45:15.805 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Table/View "remoterepositoryrequest" has been initialised
11:45:15.805 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@17977f41 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
11:45:15.805 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e" with isolation "serializable"
11:45:15.810 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "remoterepositoryrequest" returned table type of TABLE
11:45:15.810 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "remoterepositoryrequest" in Catalog "", Schema ""
11:45:15.818 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 7 ms
11:45:15.818 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "remoterepositoryrequest" : 6 columns found
11:45:15.824 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "remoterepositoryrequest"
11:45:15.839 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "remoterepositoryrequest"
11:45:15.841 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "remoterepositoryrequest"
11:45:15.841 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "remoterepositoryrequest_repositoryid" ON "remoterepositoryrequest" ("repositoryid")
11:45:15.864 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 23 ms
11:45:15.865 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e"
11:45:15.873 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e"
11:45:15.873 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@17977f41 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.874 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@17977f41 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@6a2bb37e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:15.876 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.jdo
11:45:15.877 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.jdo
11:45:15.878 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.jdo
11:45:15.879 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.jdo
11:45:15.880 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.jdo
11:45:15.881 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.jdo
11:45:15.882 [qtp1419345767-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
11:45:15.883 [qtp1419345767-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
11:45:15.884 [qtp1419345767-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
11:45:15.884 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
11:45:15.885 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" has been specified with JDO annotations so using those.
11:45:15.887 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Populating Meta-Data
11:45:15.888 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.orm
11:45:15.889 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.orm
11:45:15.889 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.orm
11:45:15.890 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.orm
11:45:15.890 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.orm
11:45:15.890 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.orm
11:45:15.891 [qtp1419345767-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
11:45:15.892 [qtp1419345767-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
11:45:15.892 [qtp1419345767-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
11:45:15.892 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
11:45:15.893 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Initialising Meta-Data
11:45:15.893 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.FileInProgressMarker [Table : "fileinprogressmarker", InheritanceStrategy : new-table]
11:45:15.894 [qtp1419345767-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
11:45:15.894 [qtp1419345767-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)
11:45:15.894 [qtp1419345767-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") 
11:45:15.894 [qtp1419345767-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)
11:45:15.894 [qtp1419345767-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.
11:45:15.894 [qtp1419345767-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)
11:45:15.895 [qtp1419345767-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)
11:45:15.895 [qtp1419345767-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)
11:45:15.895 [qtp1419345767-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") 
11:45:15.895 [qtp1419345767-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)
11:45:15.896 [qtp1419345767-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)
11:45:15.896 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Table/View "fileinprogressmarker" has been initialised
11:45:15.896 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3afcb645 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
11:45:15.896 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373" with isolation "serializable"
11:45:15.901 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "fileinprogressmarker" returned table type of TABLE
11:45:15.901 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "fileinprogressmarker" in Catalog "", Schema ""
11:45:15.909 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 8 ms
11:45:15.909 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "fileinprogressmarker" : 7 columns found
11:45:15.915 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "fileinprogressmarker"
11:45:15.916 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid")
11:45:15.937 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 21 ms
11:45:15.964 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "fileinprogressmarker"
11:45:15.980 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "fileinprogressmarker"
11:45:15.980 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid_pathsha1" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid","pathsha1")
11:45:16.008 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - SQL Execution Time = 28 ms
11:45:16.008 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373"
11:45:16.014 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373"
11:45:16.014 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3afcb645 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:16.014 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3afcb645 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@52481373, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:16.016 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.jdo
11:45:16.018 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.jdo
11:45:16.020 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.jdo
11:45:16.021 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.jdo
11:45:16.022 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.jdo
11:45:16.024 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.jdo
11:45:16.025 [qtp1419345767-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
11:45:16.026 [qtp1419345767-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
11:45:16.028 [qtp1419345767-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
11:45:16.028 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
11:45:16.029 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" has been specified with JDO annotations so using those.
11:45:16.030 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Populating Meta-Data
11:45:16.031 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.orm
11:45:16.032 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.orm
11:45:16.032 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.orm
11:45:16.033 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.orm
11:45:16.033 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.orm
11:45:16.034 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.orm
11:45:16.034 [qtp1419345767-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
11:45:16.035 [qtp1419345767-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
11:45:16.036 [qtp1419345767-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
11:45:16.036 [qtp1419345767-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
11:45:16.036 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Initialising Meta-Data
11:45:16.037 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.Symlink [Table : "repofile", InheritanceStrategy : superclass-table]
11:45:16.037 [qtp1419345767-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") 
11:45:16.037 [qtp1419345767-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
11:45:16.037 [qtp1419345767-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)
11:45:16.038 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6b73e8ce [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" on resource "nontx" with isolation level "serializable" and auto-commit=false
11:45:16.038 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction STARTED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf" with isolation "serializable"
11:45:16.042 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repofile" returned table type of TABLE
11:45:16.043 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "repofile" in Catalog "", Schema ""
11:45:16.051 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 1 tables, time = 8 ms
11:45:16.051 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "repofile" : 13 columns found
11:45:16.059 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 6 index(es) for table "repofile"
11:45:16.071 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 foreign key(s) for table "repofile"
11:45:16.075 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repofile"
11:45:16.075 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction COMMITTING with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf"
11:45:16.076 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction CLOSED with connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf"
11:45:16.076 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6b73e8ce [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:16.076 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6b73e8ce [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@489d88cf, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]"
11:45:16.076 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.077 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@633187fc" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@5e9457a7" with txn="org.datanucleus.TransactionImpl@edf41cc"
11:45:16.077 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=613003612-11, enlisted resources=[]]
11:45:16.077 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc (optimistic=false)
11:45:16.077 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
11:45:16.078 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
11:45:16.078 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.LocalRepository]
11:45:16.078 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
11:45:16.079 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.080 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 2 ms
11:45:16.081 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@11dacd02 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
11:45:16.081 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@6eabc8b9, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=613003612-11, enlisted resources=[]]
11:45:16.082 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
11:45:16.091 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "6fe404e0-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87"
11:45:16.091 [qtp1419345767-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'
11:45:16.092 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.092 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 11 ms
11:45:16.092 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
11:45:16.093 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 2 cache
11:45:16.093 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
11:45:16.093 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="1") added to Level 2 cache (fields="[0, 1, 2, 4, 5]", version="")
11:45:16.094 [qtp1419345767-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "HOLLOW"->"P_CLEAN"
11:45:16.094 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="1") enlisted in transactional cache
11:45:16.095 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" having fields "publicKey" fetched from table ""repository""
11:45:16.102 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "9b8cc4e3-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87"
11:45:16.102 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."publickey" FROM "repository" "a0" WHERE "a0"."id" = <1>
11:45:16.102 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.103 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@422b57c4"
11:45:16.103 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="1") is having the following fields in Level 2 cache object updated : [3]
11:45:16.104 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" having fields "privateKey" fetched from table ""repository""
11:45:16.110 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "3dfe84e5-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87"
11:45:16.110 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."privatekey" FROM "repository" "a0" WHERE "a0"."id" = <1>
11:45:16.111 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.111 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@168dcc49"
11:45:16.112 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="1") is having the following fields in Level 2 cache object updated : [7]
11:45:16.112 [qtp1419345767-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]initPersistenceManagerFactory: repositoryId=1c59e3b5-2e22-4f98-ada2-46255dc86df6
11:45:16.112 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=613003612-11, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@6eabc8b9]]
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@11dacd02 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@11dacd02 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e499e87, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.113 [qtp1419345767-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committed in 2 ms
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@62782abe, lifecycle=HOLLOW]
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.114 [qtp1419345767-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2164 ms.
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@633187fc" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@5e9457a7" with txn="org.datanucleus.TransactionImpl@266df667"
11:45:16.114 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=613003612-12, enlisted resources=[]]
11:45:16.115 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc (optimistic=false)
11:45:16.115 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Single-String with "SELECT WHERE this.changed < :changed"
11:45:16.115 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
11:45:16.117 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 2 ms
11:45:16.117 [qtp1419345767-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]
11:45:16.117 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
11:45:16.118 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.119 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 2 ms
11:45:16.120 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cd16cd2 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@654679e1, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
11:45:16.120 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7da11e62, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=613003612-12, enlisted resources=[]]
11:45:16.120 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" ...
11:45:16.129 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "e07144e7-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@654679e1"
11:45:16.129 [qtp1419345767-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-02-22 11:45:16.115>
11:45:16.130 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.130 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
11:45:16.131 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc
11:45:16.131 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.131 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.131 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=613003612-12, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@7da11e62]]
11:45:16.131 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cd16cd2 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@654679e1, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.132 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cd16cd2 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@654679e1, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.132 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
11:45:16.132 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.132 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [527c8869]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
11:45:16.133 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [527c8869]tryAcquire: returning true. lockCounter=1
11:45:16.133 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [527c8869]tryAcquire: returning true. lockCounter=2
11:45:16.133 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
11:45:16.134 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [527c8869]release: NOT releasing underlying FileLock. lockCounter=1
11:45:16.134 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [527c8869]release: releasing underlying FileLock. lockCounter=0
11:45:16.134 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@633187fc" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@5e9457a7" with txn="org.datanucleus.TransactionImpl@11302394"
11:45:16.135 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=613003612-13, enlisted resources=[]]
11:45:16.135 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc (optimistic=false)
11:45:16.135 [qtp1419345767-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
11:45:16.137 [qtp1419345767-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
11:45:16.137 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@771400be [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e345151, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
11:45:16.137 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@3c20991f, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=613003612-13, enlisted resources=[]]
11:45:16.137 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
11:45:16.137 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "6fe404e0-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e345151"
11:45:16.138 [qtp1419345767-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'
11:45:16.138 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.138 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
11:45:16.139 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
11:45:16.139 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
11:45:16.139 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
11:45:16.140 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
11:45:16.140 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="1") enlisted in transactional cache
11:45:16.140 [qtp1419345767-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@50716a95"
11:45:16.142 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" 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]
11:45:16.142 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="1") is having the following fields in Level 2 cache object updated : [6]
11:45:16.142 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" field "aliases" loading contents to SCO wrapper from the datastore
11:45:16.154 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "4c1f44ea-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e345151"
11:45:16.154 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
11:45:16.154 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.155 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@55d8f07a"
11:45:16.155 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [1eff51cd]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
11:45:16.155 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [1eff51cd]tryAcquire: returning true. lockCounter=1
11:45:16.156 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
11:45:16.156 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [1eff51cd]release: releasing underlying FileLock. lockCounter=0
11:45:16.157 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.local.LocalRepoManagerImpl - syncWithLocalRepoRegistry: Alias 'k6ywnxyu-xjxzj' of repository '1c59e3b5-2e22-4f98-ada2-46255dc86df6' already in-sync.
11:45:16.157 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [70316c67]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
11:45:16.157 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [70316c67]tryAcquire: returning true. lockCounter=1
11:45:16.157 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [70316c67]tryAcquire: returning true. lockCounter=2
11:45:16.157 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
11:45:16.158 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [70316c67]release: NOT releasing underlying FileLock. lockCounter=1
11:45:16.158 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [70316c67]release: releasing underlying FileLock. lockCounter=0
11:45:16.158 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.158 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.158 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
11:45:16.158 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.158 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=613003612-13, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@3c20991f]]
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@771400be [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e345151, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.159 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@771400be [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@1e345151, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.160 [qtp1419345767-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
11:45:16.160 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
11:45:16.160 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
11:45:16.161 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@50716a95, lifecycle=HOLLOW]
11:45:16.161 [qtp1419345767-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
11:45:16.161 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.162 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@633187fc" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@5e9457a7" with txn="org.datanucleus.TransactionImpl@7c419144"
11:45:16.162 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=613003612-14, enlisted resources=[]]
11:45:16.162 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc (optimistic=false)
11:45:16.163 [qtp1419345767-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
11:45:16.163 [qtp1419345767-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
11:45:16.164 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@1673f8a4 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
11:45:16.164 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@25324a0d, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=613003612-14, enlisted resources=[]]
11:45:16.164 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
11:45:16.165 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "6fe404e0-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95"
11:45:16.165 [qtp1419345767-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'
11:45:16.165 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.166 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 2 ms
11:45:16.166 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache
11:45:16.166 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
11:45:16.166 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
11:45:16.167 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
11:45:16.167 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="1") enlisted in transactional cache
11:45:16.167 [qtp1419345767-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@7ad595db"
11:45:16.168 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" 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]
11:45:16.168 [qtp1419345767-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="1") is having the following fields in Level 2 cache object updated : [6]
11:45:16.177 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "c7cf84ed-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95"
11:45:16.177 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT(*) FROM "localrepository_aliases" THIS WHERE THIS."id_oid"=<1>
11:45:16.178 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.178 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@37730de8"
11:45:16.178 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" field "aliases" loading contents to SCO wrapper from the datastore
11:45:16.179 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "4c1f44ea-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95"
11:45:16.179 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
11:45:16.179 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@3b9e2f59"
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.180 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.180 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=613003612-14, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@25324a0d]]
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@1673f8a4 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@1673f8a4 [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@7cfbac95, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
11:45:16.181 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@7ad595db, lifecycle=HOLLOW]
11:45:16.182 [qtp1419345767-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
11:45:16.182 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.182 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]open: closing=false closeAbortable=true
11:45:16.182 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext OPENED "org.datanucleus.ExecutionContextImpl@633187fc" for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@5e9457a7" with txn="org.datanucleus.TransactionImpl@3d97c9b4"
11:45:16.182 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=613003612-15, enlisted resources=[]]
11:45:16.182 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc (optimistic=false)
11:45:16.183 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [67457f2f]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
11:45:16.183 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [67457f2f]tryAcquire: returning true. lockCounter=1
11:45:16.183 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [67457f2f]tryAcquire: returning true. lockCounter=2
11:45:16.183 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
11:45:16.183 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [67457f2f]release: NOT releasing underlying FileLock. lockCounter=1
11:45:16.184 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [67457f2f]release: releasing underlying FileLock. lockCounter=0
11:45:16.184 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
11:45:16.185 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
11:45:16.185 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepository]
11:45:16.185 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
11:45:16.185 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.186 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
11:45:16.187 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection OPENED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12fe083c [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" on resource "tx" with isolation level "read-committed" and auto-commit=false
11:45:16.187 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@70c09b29, error code TMNOFLAGS and transaction [DataNucleus Transaction, ID=613003612-15, enlisted resources=[]]
11:45:16.187 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" ...
11:45:16.193 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "038204f0-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.193 [qtp1419345767-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'
11:45:16.194 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.194 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 7 ms
11:45:16.195 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
11:45:16.195 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
11:45:16.195 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest]
11:45:16.195 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
11:45:16.196 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.197 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 2 ms
11:45:16.197 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" ...
11:45:16.202 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "15fa44f2-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.202 [qtp1419345767-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"
11:45:16.202 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.203 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 6 ms
11:45:16.203 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
11:45:16.204 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
11:45:16.204 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.NormalFile]
11:45:16.204 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
11:45:16.205 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.205 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
11:45:16.206 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" ...
11:45:16.216 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "687384f4-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.216 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'
11:45:16.218 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 2 ms
11:45:16.218 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 12 ms
11:45:16.219 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
11:45:16.219 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
11:45:16.219 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.Directory]
11:45:16.219 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
11:45:16.220 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.221 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 2 ms
11:45:16.221 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" ...
11:45:16.227 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "7aedc4f6-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.228 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'
11:45:16.228 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 0 ms
11:45:16.229 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 8 ms
11:45:16.229 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
11:45:16.230 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 1 ms
11:45:16.230 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.CopyModification]
11:45:16.230 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
11:45:16.231 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.231 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
11:45:16.232 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" ...
11:45:16.243 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "cd6904f8-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.243 [qtp1419345767-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'
11:45:16.244 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.244 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 12 ms
11:45:16.245 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (generic) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
11:45:16.245 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (generic) = 0 ms
11:45:16.245 [qtp1419345767-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.DeleteModification]
11:45:16.246 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile (datastore) of "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
11:45:16.246 [qtp1419345767-38] DEBUG DataNucleus.Query - Compile of JDOQL into SQL - JoinType : navigation(default=(using nullability), filter=(using nullability))
11:45:16.247 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time (datastore) = 1 ms
11:45:16.247 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" ...
11:45:16.258 [qtp1419345767-38] DEBUG DataNucleus.Datastore - Using PreparedStatement "dfe544fa-0170-71a6-6a77-00000145bee8" for connection "org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669"
11:45:16.258 [qtp1419345767-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'
11:45:16.259 [qtp1419345767-38] DEBUG DataNucleus.Datastore.Retrieve - SQL Execution Time = 1 ms
11:45:16.259 [qtp1419345767-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 12 ms
11:45:16.259 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.259 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.260 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.local.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@633187fc
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() START : using ordered flush - 0 enlisted objects
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() END
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=613003612-15, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@70c09b29]]
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection COMMITTING : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12fe083c [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.260 [qtp1419345767-38] DEBUG DataNucleus.Connection - ManagedConnection CLOSED : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12fe083c [conn=org.datanucleus.store.rdbms.datasource.dbcp2.PoolingDataSource$PoolGuardConnectionWrapper@10e00669, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]"
11:45:16.261 [qtp1419345767-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
11:45:16.261 [qtp1419345767-38] DEBUG DataNucleus.Persistence - ExecutionContext CLOSED "org.datanucleus.ExecutionContextImpl@633187fc"
11:45:16.261 [qtp1419345767-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]getCloseDeferredMillis: closeDeferredMillis=0
11:45:16.261 [qtp1419345767-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]close: Closing real LocalRepoManager immediately.
11:45:16.261 [qtp1419345767-38] INFO co.codewizards.cloudstore.local.LocalRepoManagerImpl - [45860db9]_close: Shutting down real LocalRepoManager.
11:45:16.261 [qtp1419345767-38] DEBUG DataNucleus.Connection - Closing ConnectionPool "tx"
11:45:16.262 [qtp1419345767-38] DEBUG DataNucleus.Connection - Closing ConnectionPool "nontx"
11:45:16.263 [qtp1419345767-38] DEBUG DataNucleus.Persistence - Unmanaging Persistence of all classes
11:45:16.264 [qtp1419345767-38] DEBUG DataNucleus.MetaData - Deregistering listener for metadata initialisation
11:45:16.264 [qtp1419345767-38] DEBUG DataNucleus.Cache - Level 2 Cache cleared
11:45:16.758 [qtp1419345767-38] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [56fe481]release: releasing underlying FileLock. lockCounter=0
11:45:16.762 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@6797650c[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@108a7a55{null}
11:45:16.762 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /RepoInfo on HttpChannelOverHttp@34bf8429{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:34683/RepoInfo,age=2848}
200 OK HTTP/1.1
Content-Length: 773
Content-Type: application/xml


11:45:16.762 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4c987d76[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@202037de] generate: NEED_HEADER (null,[p=0,l=773,c=8192,r=773],true)@START
11:45:16.763 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=200,h=2,cl=773} last=true content=HeapByteBuffer@6797650c[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}
11:45:16.763 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpGenerator - Content-Length: 773
Content-Type: application/xml


11:45:16.763 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
11:45:16.763 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4c987d76[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@202037de] generate: FLUSH ([p=0,l=104,c=8192,r=104],[p=0,l=773,c=8192,r=773],true)@COMPLETING
11:45:16.763 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@7cc399d3{IDLE}->null [HeapByteBuffer@26152aea[p=0,l=104,c=8192,r=104]={<<<HTTP/1.1 200 OK\r\n....v20190418)\r\n\r\n>>>VSQzd0WHJhY3pYc0R...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@6797650c[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}]
11:45:16.764 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@7cc399d3{WRITING}->null:IDLE-->WRITING
11:45:16.765 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 877 SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=W,to=2852/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=END,244 of 244},g=HttpGenerator@cebcd79{s=COMPLETING}]=>HttpChannelOverHttp@34bf8429{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:34683/RepoInfo,age=2851}
11:45:16.765 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=877 remaining=0 WriteFlusher@7cc399d3{WRITING}->null
11:45:16.765 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@7cc399d3{IDLE}->null:WRITING-->IDLE
11:45:16.765 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@4c987d76[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=773},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@202037de] generate: DONE ([p=104,l=104,c=8192,r=0],[p=773,l=773,c=8192,r=0],true)@END
11:45:16.766 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.session - FinalSession=null, old_session_handler=null, this=org.eclipse.jetty.server.session.SessionHandler322391206==dftMaxIdleSec=-1, calling complete=true
11:45:16.766 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.session - Complete called with session null
11:45:16.766 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@34bf8429{r=1,c=true,c=false/true,a=DISPATCHED,uri=//127.0.0.1:34683/RepoInfo,age=2852}
11:45:16.767 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@30801666{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
11:45:16.767 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@34bf8429{r=1,c=true,c=false/true,a=COMPLETING,uri=//127.0.0.1:34683/RepoInfo,age=2853} action COMPLETE
11:45:16.767 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@30801666{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
11:45:16.767 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /RepoInfo written=773
11:45:16.767 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@30801666{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
11:45:16.768 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,244 of 244}
11:45:16.768 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
11:45:16.768 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
11:45:16.768 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@26152aea[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}
11:45:16.769 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@26152aea[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}
11:45:16.769 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@26152aea[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}
11:45:16.770 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@26152aea[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} {}
11:45:16.770 [qtp1419345767-38] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@26152aea[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}
11:45:16.780 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
11:45:16.782 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=-,to=15/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.783 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=-,flush=-,to=17/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.783 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@443d9e01{AC.ReadCB@75828e12{HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
11:45:16.784 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.784 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@74be83c6 on ManagedSelector@45b8bbbf{STARTED} id=1 keys=1 selected=0 updates=0
11:45:16.784 [qtp1419345767-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@45b8bbbf{STARTED} id=1 keys=1 selected=0 updates=1
11:45:16.784 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken with none selected
11:45:16.784 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken up from select, 0/0/1 selected
11:45:16.784 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 processing 0 keys, 1 updates
11:45:16.784 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.784 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@74be83c6
11:45:16.785 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.785 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.785 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 waiting with 1 keys
11:45:16.785 [qtp1419345767-38] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@30801666{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
11:45:16.786 [qtp1419345767-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=2/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
11:45:16.793 [main] DEBUG co.codewizards.cloudstore.ls.rest.client.LocalServerRestClient - execute: invocation took 2978 ms
Local repository:
  repository.repositoryId = 1c59e3b5-2e22-4f98-ada2-46255dc86df6
  repository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/repo/k6ywnxyu-xjxzj
  repository.aliases = co.codewizards.cloudstore.ls.core.dto.RepoInfoResponseDto@3883b5e9
  repository.publicKeySha1 = EE:24:AE:A1:10:5E:86:9F:22:E3:1F:F2:0D:03:99:64:FA:3C:02:AE

Remote repositories connected: {NONE}

Remote repositories requesting connection: {NONE}

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

11:45:16.795 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping Server@bf4e48e{STARTED}[9.4.17.v20190418]
11:45:16.795 [main] DEBUG org.eclipse.jetty.server.Server - doStop Server@bf4e48e{STOPPING}[9.4.17.v20190418]
11:45:16.796 [qtp1419345767-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran qtp1419345767-35-acceptor-0@46de118b-ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:34683}
11:45:16.797 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandlerContainer - Graceful shutdown Server@bf4e48e{STOPPING}[9.4.17.v20190418] by 
11:45:16.797 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
11:45:16.797 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping SelectorManager@ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
11:45:16.797 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@323efafe{STARTED} id=2 keys=0 selected=0 updates=0
11:45:16.798 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@61becbcf on ManagedSelector@323efafe{STOPPING} id=2 keys=0 selected=0 updates=0
11:45:16.798 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@323efafe{STOPPING} id=2 keys=0 selected=0 updates=1
11:45:16.798 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 woken with none selected
11:45:16.799 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 woken up from select, 0/0/0 selected
11:45:16.799 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 processing 0 keys, 1 updates
11:45:16.799 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.799 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@61becbcf
11:45:16.800 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@323efafe{STOPPING} id=2 keys=0 selected=0 updates=0
11:45:16.800 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.800 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 waiting with 0 keys
11:45:16.800 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@20171cdc on ManagedSelector@323efafe{STOPPING} id=2 keys=0 selected=0 updates=0
11:45:16.801 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@323efafe{STOPPING} id=2 keys=0 selected=0 updates=1
11:45:16.801 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 woken with none selected
11:45:16.801 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 woken up from select, 0/0/0 selected
11:45:16.801 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@69107055 processing 0 keys, 1 updates
11:45:16.801 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.801 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@20171cdc
11:45:16.802 [qtp1419345767-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.802 [qtp1419345767-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@4182a651
11:45:16.802 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@1df06ecd/SelectorProducer@57927bc9/IDLE/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-02-23T11:45:16.802514+01:00
11:45:16.803 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@1df06ecd/SelectorProducer@57927bc9/IDLE/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-02-23T11:45:16.80326+01:00
11:45:16.803 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@323efafe{STOPPED} id=2 keys=-1 selected=-1 updates=0
11:45:16.804 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@45b8bbbf{STARTED} id=1 keys=1 selected=0 updates=0
11:45:16.804 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@58f97013 on ManagedSelector@45b8bbbf{STOPPING} id=1 keys=1 selected=0 updates=0
11:45:16.804 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@45b8bbbf{STOPPING} id=1 keys=1 selected=0 updates=1
11:45:16.804 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken with none selected
11:45:16.805 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken up from select, 0/0/1 selected
11:45:16.805 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 processing 0 keys, 1 updates
11:45:16.805 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.805 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@58f97013
11:45:16.805 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 1 connections on ManagedSelector@45b8bbbf{STOPPING} id=1 keys=1 selected=0 updates=0
11:45:16.806 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=22/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.806 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,OPEN,fill=FI,flush=-,to=23/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.806 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,CLOSED,fill=FI,flush=-,to=23/30000}{io=1/1,kio=1,kro=1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.807 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@7cc399d3{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)
11:45:16.809 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@443d9e01{AC.ReadCB@75828e12{HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,CLOSED,fill=FI,flush=-,to=25/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@75828e12[p=HttpParser{s=START,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
11:45:16.809 [qtp1419345767-33] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
11:45:16.809 [qtp1419345767-33] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
11:45:16.809 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,CLOSED,fill=-,flush=-,to=26/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@75828e12[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed {}
11:45:16.810 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@45b8bbbf{STOPPING} id=1 keys=1 selected=0 updates=0
11:45:16.810 [qtp1419345767-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@34bed530
11:45:16.810 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.810 [qtp1419345767-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@34bed530
11:45:16.810 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 waiting with 1 keys
11:45:16.810 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@7d70ba18 on ManagedSelector@45b8bbbf{STOPPING} id=1 keys=1 selected=0 updates=0
11:45:16.810 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@45b8bbbf{STOPPING} id=1 keys=0 selected=0 updates=1
11:45:16.811 [qtp1419345767-39] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,CLOSED,fill=-,flush=-,to=27/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@75828e12[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.811 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken with none selected
11:45:16.811 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 woken up from select, 0/0/0 selected
11:45:16.811 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b442e13 processing 0 keys, 1 updates
11:45:16.811 [qtp1419345767-39] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose HttpConnection@75828e12::SocketChannelEndPoint@3a787d16{/127.0.0.1:50274<->/127.0.0.1:34683,CLOSED,fill=-,flush=-,to=28/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@75828e12[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cebcd79{s=START}]=>HttpChannelOverHttp@34bf8429{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
11:45:16.811 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.811 [qtp1419345767-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@34bed530
11:45:16.811 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@7d70ba18
11:45:16.812 [qtp1419345767-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.812 [qtp1419345767-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@684b26b7
11:45:16.813 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@2adc1e84/SelectorProducer@2a99fa07/IDLE/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-02-23T11:45:16.812693+01:00
11:45:16.813 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@2adc1e84/SelectorProducer@2a99fa07/IDLE/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=6,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-02-23T11:45:16.81354+01:00
11:45:16.814 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@45b8bbbf{STOPPED} id=1 keys=-1 selected=-1 updates=0
11:45:16.814 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ManagedSelector@1c788d08{STARTED} id=0 keys=0 selected=0 updates=0
11:45:16.814 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@6a07e6ca on ManagedSelector@1c788d08{STOPPING} id=0 keys=0 selected=0 updates=0
11:45:16.814 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1c788d08{STOPPING} id=0 keys=0 selected=0 updates=1
11:45:16.815 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 woken with none selected
11:45:16.815 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 woken up from select, 0/0/0 selected
11:45:16.815 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 processing 0 keys, 1 updates
11:45:16.815 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.815 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@6a07e6ca
11:45:16.816 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@1c788d08{STOPPING} id=0 keys=0 selected=0 updates=0
11:45:16.816 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.816 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 waiting with 0 keys
11:45:16.816 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@726ef6aa on ManagedSelector@1c788d08{STOPPING} id=0 keys=0 selected=0 updates=0
11:45:16.816 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1c788d08{STOPPING} id=0 keys=0 selected=0 updates=1
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 woken with none selected
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 woken up from select, 0/0/0 selected
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@2c904619 processing 0 keys, 1 updates
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@726ef6aa
11:45:16.817 [qtp1419345767-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
11:45:16.818 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping EatWhatYouKill@126254ec/SelectorProducer@275902e1/PRODUCING/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=6,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-02-23T11:45:16.818282+01:00
11:45:16.818 [qtp1419345767-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$231/0x0000000800320840@32dbca45
11:45:16.819 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED EatWhatYouKill@126254ec/SelectorProducer@275902e1/IDLE/p=false/QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-02-23T11:45:16.818868+01:00
11:45:16.819 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ManagedSelector@1c788d08{STOPPED} id=0 keys=-1 selected=-1 updates=0
11:45:16.819 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED SelectorManager@ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
11:45:16.819 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HttpConnectionFactory@189633f2[HTTP/1.1]
11:45:16.819 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HttpConnectionFactory@189633f2[HTTP/1.1]
11:45:16.820 [main] INFO org.eclipse.jetty.server.AbstractConnector - Stopped ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
11:45:16.820 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ServerConnector@2b974137{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
11:45:16.820 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping Server@bf4e48e{STOPPING}[9.4.17.v20190418]
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping DefaultSessionIdManager@430aae8e{STARTED}[worker=node0]
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping HouseKeeper@78504ce9{STARTED}[interval=660000, ownscheduler=false]
11:45:16.821 [main] INFO org.eclipse.jetty.server.session - node0 Stopped scavenging
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED HouseKeeper@78504ce9{STOPPED}[interval=660000, ownscheduler=false]
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED DefaultSessionIdManager@430aae8e{STOPPED}[worker=node0]
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ErrorHandler@6ae6d078{STARTED}
11:45:16.821 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping ErrorHandler@6ae6d078{STOPPING}
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ErrorHandler@6ae6d078{STOPPED}
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping o.e.j.s.ServletContextHandler@2382b2f{/,null,SHUTDOWN}
11:45:16.821 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandlerContainer - Graceful shutdown o.e.j.s.ServletContextHandler@2382b2f{/,null,SHUTDOWN} by 
11:45:16.821 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping o.e.j.s.ServletContextHandler@2382b2f{/,null,UNAVAILABLE}
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.SessionHandler322391206==dftMaxIdleSec=-1
11:45:16.821 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.DefaultSessionCache@5e1d1524[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.NullSessionDataStore@d0a2c82[passivating=false,graceSec=3600]
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.NullSessionDataStore@d0a2c82[passivating=false,graceSec=3600]
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.DefaultSessionCache@5e1d1524[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
11:45:16.822 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.session.SessionHandler322391206==dftMaxIdleSec=-1
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ServletHandler@71734463{STARTED}
11:45:16.822 [main] DEBUG org.eclipse.jetty.server.handler.AbstractHandler - stopping ServletHandler@71734463{STOPPING}
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-12608986@f31e5e6e==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-12608986@f31e5e6e==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
11:45:16.822 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping org.glassfish.jersey.servlet.ServletContainer-3eff6846@1d70ef24==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
11:45:16.835 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.glassfish.jersey.servlet.ServletContainer-3eff6846@1d70ef24==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
11:45:16.836 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ServletHandler@71734463{STOPPED}
11:45:16.836 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.SessionHandler322391206==dftMaxIdleSec=-1
11:45:16.836 [main] INFO org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.s.ServletContextHandler@2382b2f{/,null,UNAVAILABLE}
11:45:16.836 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED o.e.j.s.ServletContextHandler@2382b2f{/,null,UNAVAILABLE}
11:45:16.836 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@299eca90{STARTED}
11:45:16.837 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@299eca90{STOPPED}
11:45:16.837 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping QueuedThreadPool[qtp1419345767]@54997f67{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}]
11:45:16.837 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Stopping QueuedThreadPool[qtp1419345767]@54997f67{STOPPING,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@6ca33187{s=1/6,p=0}]
11:45:16.838 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - stopping ReservedThreadExecutor@6ca33187{s=1/6,p=0}
11:45:16.838 [main] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@6ca33187{s=0/6,p=0}@71bc4563 offer STOP!
11:45:16.838 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED ReservedThreadExecutor@6ca33187{s=0/6,p=0}
11:45:16.838 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[qtp1419345767-39,5,main] for 14999
11:45:16.838 [qtp1419345767-37] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@6ca33187{s=0/6,p=0}@71bc4563 task=STOP!
11:45:16.839 [qtp1419345767-37] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@6ca33187{s=0/6,p=0}@71bc4563 Exited
11:45:16.839 [qtp1419345767-37] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@6ca33187{s=0/6,p=0}@71bc4563
11:45:16.840 [qtp1419345767-36] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.840 [qtp1419345767-36] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.840 [qtp1419345767-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.840 [qtp1419345767-38] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.840 [qtp1419345767-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.840 [qtp1419345767-35] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-34] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-39] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.841 [qtp1419345767-32] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.842 [qtp1419345767-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.842 [qtp1419345767-33] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$321/0x0000000800944c40@3b45bb12
11:45:16.843 [main] DEBUG org.eclipse.jetty.util.thread.QueuedThreadPool - Waiting for Thread[qtp1419345767-38,5,] for 14995
11:45:16.843 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED QueuedThreadPool[qtp1419345767]@54997f67{STOPPED,8<=0<=500,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$67/0x00000008001e4840@554e218]
11:45:16.844 [main] DEBUG org.eclipse.jetty.util.component.AbstractLifeCycle - STOPPED Server@bf4e48e{STOPPED}[9.4.17.v20190418]
11:45:16.844 [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
11:45:16.844 [main] DEBUG co.codewizards.cloudstore.core.io.LockFileImpl - [18e8eb59]release: releasing underlying FileLock. lockCounter=0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 26.614 sec

Results :

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

[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.client ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0.jar
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy-dependencies (copy-dependencies) @ co.codewizards.cloudstore.client ---
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.rest.client-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.client-1.0.0.jar
[INFO] Copying co.codewizards.cloudstore.updater-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.updater-1.0.0.jar
[INFO] 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
[INFO] 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
[INFO] Copying datanucleus-api-jdo-5.2.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/datanucleus-api-jdo-5.2.2.jar
[INFO] 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
[INFO] Copying javax.jdo-3.2.0-m12.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/javax.jdo-3.2.0-m12.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying datanucleus-rdbms-5.2.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/datanucleus-rdbms-5.2.1.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.local-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.local-1.0.0.jar
[INFO] Copying co.codewizards.cloudstore.rest.shared-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.shared-1.0.0.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.server-1.0.0.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.0.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.ls.server.cproc-1.0.0.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.0.jar
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.ls.server-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server-1.0.0.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.core.oio.nio-1.0.0.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.0.jar
[INFO] Copying co.codewizards.cloudstore.core-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core-1.0.0.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] Copying co.codewizards.cloudstore.ls.core-1.0.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.core-1.0.0.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.client-1.0.0.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.0.jar
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 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
[INFO] 
[INFO] --- maven-assembly-plugin:2.2-beta-5:single (make-assembly) @ co.codewizards.cloudstore.client ---
[INFO] Reading assembly descriptor: src/assembly/bin.xml
[INFO] Building tar : /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-bin.tar.gz
[INFO] Building zip: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-bin.zip
[INFO] 
[INFO] --- maven-source-plugin:3.2.1:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.client ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-sources.jar
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ co.codewizards.cloudstore.client ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0.jar
[INFO] 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.0/co.codewizards.cloudstore.client-1.0.0.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-bin.tar.gz to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.tar.gz
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-bin.zip to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.zip
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-1.0.0-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.client ---
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0.jar
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0.jar (45 kB at 22 MB/s)
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0.pom
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0.pom (3.8 kB at 3.8 MB/s)
[INFO] Downloading from cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[INFO] Downloaded from cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (876 B at 438 kB/s)
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (876 B at 876 kB/s)
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.tar.gz
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.tar.gz (23 MB at 54 MB/s)
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.zip
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-bin.zip (23 MB at 57 MB/s)
[INFO] Uploading to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-sources.jar
[INFO] Uploaded to cloudstore-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/1.0.0/co.codewizards.cloudstore.client-1.0.0-sources.jar (29 kB at 29 MB/s)
[INFO] 
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.client ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.client
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.client/de
[INFO] 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
[INFO] 
[INFO] >>> maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) > generate-sources @ co.codewizards.cloudstore.client >>>
[WARNING] Failed to getClass for org.apache.maven.plugins.javadoc.JavadocReport
[JENKINS] Archiving  javadoc
[INFO] 
[INFO] <<< maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) < generate-sources @ co.codewizards.cloudstore.client <<<
[INFO] 
[INFO] 
[INFO] --- maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.client ---
[INFO] Skipping javadoc generation
[JENKINS] Archiving  javadoc
[INFO] 
[INFO] --- maven-resources-plugin:2.6:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.client ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[INFO] 
[INFO] --- maven-resources-plugin:2.6:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.client ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/apidocs
[INFO] 
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.client ---
file:/tmp/cloudstore-junk/ - Session: Opened  
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.client/target/site
[INFO]    >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.client
[INFO]    >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.client
file:/tmp/cloudstore-junk/ - Session: Disconnecting  
file:/tmp/cloudstore-junk/ - Session: Disconnected