Console Output

.Schema - Validating 1 unique key(s) for table "remoterepositoryrequest"
19:08:33.574 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Creating candidate key "RemoteRepositoryRequest_repositoryId" in catalog "" schema ""
19:08:33.574 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "remoterepositoryrequest_repositoryid" ON "remoterepositoryrequest" ("repositoryid")
19:08:33.623 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Execution Time = 49 ms
19:08:33.624 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@446e34c2"
19:08:33.631 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@446e34c2"
19:08:33.632 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5fea3708 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@446e34c2, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
19:08:33.632 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5fea3708 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@446e34c2, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
19:08:33.636 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.jdo
19:08:33.637 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.jdo
19:08:33.639 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.jdo
19:08:33.640 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.jdo
19:08:33.641 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co.jdo
19:08:33.642 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.jdo
19:08:33.642 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards.jdo
19:08:33.643 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.jdo
19:08:33.644 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore.jdo
19:08:33.645 [qtp609375192-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
19:08:33.645 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local.jdo
19:08:33.646 [qtp609375192-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
19:08:33.647 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
19:08:33.648 [qtp609375192-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
19:08:33.648 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
19:08:33.648 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" has been specified with JDO annotations so using those.
19:08:33.649 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Populating Meta-Data
19:08:33.650 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.orm
19:08:33.651 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.orm
19:08:33.652 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.orm
19:08:33.652 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.orm
19:08:33.653 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co.orm
19:08:33.654 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.orm
19:08:33.654 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards.orm
19:08:33.655 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.orm
19:08:33.655 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore.orm
19:08:33.656 [qtp609375192-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
19:08:33.657 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local.orm
19:08:33.658 [qtp609375192-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
19:08:33.658 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence.orm
19:08:33.659 [qtp609375192-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
19:08:33.659 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
19:08:33.660 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Initialising Meta-Data
19:08:33.660 [qtp609375192-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.FileInProgressMarker [Table : "fileinprogressmarker", InheritanceStrategy : new-table]
19:08:33.660 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."id"" added to internal representation of table.
19:08:33.661 [qtp609375192-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.datastore.BigIntRDBMSMapping)
19:08:33.661 [qtp609375192-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") 
19:08:33.661 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."fromrepositoryid"" added to internal representation of table.
19:08:33.661 [qtp609375192-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.datastore.VarCharRDBMSMapping)
19:08:33.661 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."path"" added to internal representation of table.
19:08:33.661 [qtp609375192-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.datastore.ClobRDBMSMapping)
19:08:33.661 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."pathsha1"" added to internal representation of table.
19:08:33.662 [qtp609375192-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.datastore.VarCharRDBMSMapping)
19:08:33.662 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."torepositoryid"" added to internal representation of table.
19:08:33.662 [qtp609375192-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.datastore.VarCharRDBMSMapping)
19:08:33.662 [qtp609375192-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") 
19:08:33.662 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."changed"" added to internal representation of table.
19:08:33.663 [qtp609375192-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.datastore.TimestampRDBMSMapping)
19:08:33.663 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."created"" added to internal representation of table.
19:08:33.663 [qtp609375192-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.datastore.TimestampRDBMSMapping)
19:08:33.663 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Table/View "fileinprogressmarker" has been initialised
19:08:33.664 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37e1a6aa [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
19:08:33.664 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@400f5d97" with isolation "serializable"
19:08:33.668 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "fileinprogressmarker" returned table type of TABLE
19:08:33.669 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "fileinprogressmarker, copymodification, lastsynctoremoterepo, remoterepositoryrequest, localrepository_aliases, repofile, deletemodification, repository, modification, filechunk" in Catalog "", Schema ""
19:08:33.698 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 10 tables, time = 29 ms
19:08:33.698 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "fileinprogressmarker" : 7 columns found
19:08:33.706 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "fileinprogressmarker"
19:08:33.706 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Creating index "FileInProgressMark_fromRepositoryId_toRepositoryId" in catalog "" schema ""
19:08:33.706 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid")
19:08:33.727 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Execution Time = 21 ms
19:08:33.758 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "fileinprogressmarker"
19:08:33.776 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "fileinprogressmarker"
19:08:33.776 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Creating candidate key "FileInProgressMark_fromRepositoryId_toRepositoryId_pathSha1" in catalog "" schema ""
19:08:33.776 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid_pathsha1" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid","pathsha1")
19:08:33.806 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Execution Time = 30 ms
19:08:33.806 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@400f5d97"
19:08:33.812 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@400f5d97"
19:08:33.812 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37e1a6aa [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@400f5d97, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
19:08:33.812 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37e1a6aa [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@400f5d97, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
19:08:33.814 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.jdo
19:08:33.814 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.jdo
19:08:33.815 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.jdo
19:08:33.815 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.jdo
19:08:33.816 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co.jdo
19:08:33.817 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.jdo
19:08:33.817 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards.jdo
19:08:33.818 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.jdo
19:08:33.818 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore.jdo
19:08:33.819 [qtp609375192-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
19:08:33.819 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local.jdo
19:08:33.820 [qtp609375192-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
19:08:33.821 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
19:08:33.822 [qtp609375192-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
19:08:33.822 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
19:08:33.822 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" has been specified with JDO annotations so using those.
19:08:33.823 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Populating Meta-Data
19:08:33.824 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.orm
19:08:33.824 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.orm
19:08:33.825 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.orm
19:08:33.826 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.orm
19:08:33.827 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co.orm
19:08:33.828 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.orm
19:08:33.828 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards.orm
19:08:33.829 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.orm
19:08:33.830 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore.orm
19:08:33.831 [qtp609375192-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
19:08:33.832 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local.orm
19:08:33.833 [qtp609375192-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
19:08:33.833 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence.orm
19:08:33.834 [qtp609375192-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
19:08:33.834 [qtp609375192-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
19:08:33.834 [qtp609375192-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Initialising Meta-Data
19:08:33.835 [qtp609375192-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.Symlink [Table : "repofile", InheritanceStrategy : superclass-table]
19:08:33.835 [qtp609375192-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") 
19:08:33.835 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Column ""repofile"."target"" added to internal representation of table.
19:08:33.835 [qtp609375192-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
19:08:33.835 [qtp609375192-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.datastore.VarCharRDBMSMapping)
19:08:33.838 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7943f223 [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
19:08:33.838 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@4c205ec4" with isolation "serializable"
19:08:33.843 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repofile" returned table type of TABLE
19:08:33.852 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 6 index(es) for table "repofile"
19:08:33.871 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 foreign key(s) for table "repofile"
19:08:33.873 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repofile"
19:08:33.874 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@4c205ec4"
19:08:33.874 [qtp609375192-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@4c205ec4"
19:08:33.874 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7943f223 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@4c205ec4, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
19:08:33.874 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@7943f223 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@4c205ec4, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
19:08:33.874 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:33.875 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:33.875 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@2dece364" with txn="org.datanucleus.TransactionImpl@2a92d8fc"
19:08:33.875 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.875 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5 (optimistic=false)
19:08:33.875 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
19:08:33.876 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
19:08:33.876 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.LocalRepository]
19:08:33.876 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" for datastore
19:08:33.877 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:33.877 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository Query compiled to datastore query "SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'"
19:08:33.878 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
19:08:33.878 [qtp609375192-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@3b2adf8e, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.879 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
19:08:33.879 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.879 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
19:08:33.888 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@32bf8d09"
19:08:33.888 [qtp609375192-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'
19:08:33.888 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
19:08:33.889 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
19:08:33.889 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
19:08:33.889 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 2 cache
19:08:33.889 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
19:08:33.890 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="1") added to Level 2 cache (fields="[0, 1, 2, 4, 5]", version="")
19:08:33.891 [qtp609375192-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "HOLLOW"->"P_CLEAN"
19:08:33.891 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="1") enlisted in transactional cache
19:08:33.892 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" having fields "publicKey" fetched from table ""repository""
19:08:33.892 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.898 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@30920068"
19:08:33.898 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."publickey" FROM "repository" "a0" WHERE "a0"."id" = <1>
19:08:33.899 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.899 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@1bf8b5b3"
19:08:33.900 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="1") is having the following fields in Level 2 cache object updated : [3]
19:08:33.901 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" having fields "privateKey" fetched from table ""repository""
19:08:33.901 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.906 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@54eae908"
19:08:33.906 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."privatekey" FROM "repository" "a0" WHERE "a0"."id" = <1>
19:08:33.907 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.907 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@51846b81"
19:08:33.907 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="1") is having the following fields in Level 2 cache object updated : [7]
19:08:33.908 [qtp609375192-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4dec6380]initPersistenceManagerFactory: repositoryId=faf25097-7f0a-4266-84c9-253892721720
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@3b2adf8e]]
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
19:08:33.908 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@64ff1596 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@13b4c03f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@76922780" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@76922780 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@76922780, lifecycle=HOLLOW]
19:08:33.909 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:33.910 [qtp609375192-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4dec6380]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2556 ms.
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@2dece364" with txn="org.datanucleus.TransactionImpl@34550264"
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5 (optimistic=false)
19:08:33.910 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Single-String with "SELECT WHERE this.changed < :changed"
19:08:33.911 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
19:08:33.912 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
19:08:33.912 [qtp609375192-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]
19:08:33.912 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" for datastore
19:08:33.914 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
19:08:33.914 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed Query compiled to datastore query "SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0" WHERE "a0"."changed" < ?"
19:08:33.914 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
19:08:33.914 [qtp609375192-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@21534a55, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.914 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@37cbce08, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
19:08:33.915 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@37cbce08, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.915 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" ...
19:08:33.922 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@47468a9"
19:08:33.923 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0" WHERE "a0"."changed" < <2020-04-19 19:08:33.91>
19:08:33.923 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@21534a55]]
19:08:33.924 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@37cbce08, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
19:08:33.925 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@37cbce08, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
19:08:33.925 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3d8929a2 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@37cbce08, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.925 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
19:08:33.925 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:33.925 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:33.925 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [f409700]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
19:08:33.925 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [f409700]tryAcquire: returning true. lockCounter=1
19:08:33.926 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [f409700]tryAcquire: returning true. lockCounter=2
19:08:33.926 [qtp609375192-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
19:08:33.926 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [f409700]release: NOT releasing underlying FileLock. lockCounter=1
19:08:33.926 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [f409700]release: releasing underlying FileLock. lockCounter=0
19:08:33.927 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@2dece364" with txn="org.datanucleus.TransactionImpl@219ad471"
19:08:33.927 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
19:08:33.927 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5 (optimistic=false)
19:08:33.928 [qtp609375192-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
19:08:33.928 [qtp609375192-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
19:08:33.928 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
19:08:33.929 [qtp609375192-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@4b9fd682, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
19:08:33.929 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=
" with flags "0"
19:08:33.929 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.929 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
19:08:33.929 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@21e5a78a"
19:08:33.929 [qtp609375192-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'
19:08:33.930 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.930 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
19:08:33.930 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
19:08:33.930 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
19:08:33.930 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
19:08:33.931 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
19:08:33.931 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="1") enlisted in transactional cache
19:08:33.931 [qtp609375192-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@3a8fbbf4"
19:08:33.932 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" 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]
19:08:33.933 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="1") is having the following fields in Level 2 cache object updated : [6]
19:08:33.933 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" field "aliases" loading contents to SCO wrapper from the datastore
19:08:33.934 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.946 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@516fb506"
19:08:33.946 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
19:08:33.947 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.947 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@724b85f1"
19:08:33.948 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [248550b7]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
19:08:33.948 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [248550b7]tryAcquire: returning true. lockCounter=1
19:08:33.948 [qtp609375192-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
19:08:33.948 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [248550b7]release: releasing underlying FileLock. lockCounter=0
19:08:33.949 [qtp609375192-38] DEBUG c.c.c.local.LocalRepoManagerImpl - syncWithLocalRepoRegistry: Alias 'k98qgde5-4qdl7' of repository 'faf25097-7f0a-4266-84c9-253892721720' already in-sync.
19:08:33.949 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8004766]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
19:08:33.949 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8004766]tryAcquire: returning true. lockCounter=1
19:08:33.949 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8004766]tryAcquire: returning true. lockCounter=2
19:08:33.949 [qtp609375192-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
19:08:33.950 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8004766]release: NOT releasing underlying FileLock. lockCounter=1
19:08:33.950 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8004766]release: releasing underlying FileLock. lockCounter=0
19:08:33.950 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.950 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.950 [qtp609375192-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.951 [qtp609375192-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=
, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@4b9fd682]]
19:08:33.952 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=
" with onePhase="true"
19:08:33.952 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
19:08:33.952 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3572d81a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@25c4abbb, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.952 [qtp609375192-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committed in 2 ms
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@3a8fbbf4, lifecycle=HOLLOW]
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:33.953 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:33.954 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@2dece364" with txn="org.datanucleus.TransactionImpl@50e7ab96"
19:08:33.954 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.954 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5 (optimistic=false)
19:08:33.954 [qtp609375192-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
19:08:33.954 [qtp609375192-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
19:08:33.955 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
19:08:33.955 [qtp609375192-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@2344e7a0, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.955 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
19:08:33.955 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.955 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
19:08:33.956 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@6b176aa6"
19:08:33.956 [qtp609375192-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'
19:08:33.956 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
19:08:33.956 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
19:08:33.957 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
19:08:33.957 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
19:08:33.957 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
19:08:33.958 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
19:08:33.958 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="1") enlisted in transactional cache
19:08:33.958 [qtp609375192-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@1905c408"
19:08:33.958 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" 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]
19:08:33.959 [qtp609375192-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="1") is having the following fields in Level 2 cache object updated : [6]
19:08:33.959 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.966 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@7f4c1d3b"
19:08:33.967 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT(*) FROM "localrepository_aliases" THIS WHERE THIS."id_oid"=<1>
19:08:33.968 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.968 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@5d29fd1a"
19:08:33.969 [qtp609375192-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" field "aliases" loading contents to SCO wrapper from the datastore
19:08:33.969 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.970 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@6d29aa50"
19:08:33.970 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
19:08:33.970 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
19:08:33.970 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@601d7cf7"
19:08:33.971 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.971 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.971 [qtp609375192-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
19:08:33.971 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.971 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.971 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@2344e7a0]]
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4e13e1dc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@333e96e3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.972 [qtp609375192-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committed in 2 ms
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@1905c408, lifecycle=HOLLOW]
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:33.973 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:33.973 [qtp609375192-38] DEBUG c.c.c.local.LocalRepoManagerImpl - [4dec6380]open: closing=false closeAbortable=true
19:08:33.974 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@2dece364" with txn="org.datanucleus.TransactionImpl@5295d3f0"
19:08:33.974 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.974 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5 (optimistic=false)
19:08:33.974 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [330dee3c]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
19:08:33.974 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [330dee3c]tryAcquire: returning true. lockCounter=1
19:08:33.975 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [330dee3c]tryAcquire: returning true. lockCounter=2
19:08:33.975 [qtp609375192-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
19:08:33.975 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [330dee3c]release: NOT releasing underlying FileLock. lockCounter=1
19:08:33.976 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [330dee3c]release: releasing underlying FileLock. lockCounter=0
19:08:33.976 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
19:08:33.976 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
19:08:33.976 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepository]
19:08:33.977 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" for datastore
19:08:33.978 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:33.978 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository Query compiled to datastore query "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'"
19:08:33.979 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
19:08:33.979 [qtp609375192-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@11e7005e, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
19:08:33.979 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
19:08:33.979 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.979 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" ...
19:08:33.986 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@254fd008"
19:08:33.986 [qtp609375192-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'
19:08:33.987 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.987 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 8 ms
19:08:33.988 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
19:08:33.988 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
19:08:33.988 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest]
19:08:33.988 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" for datastore
19:08:33.990 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:33.990 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest Query compiled to datastore query "SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0""
19:08:33.990 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:33.990 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" ...
19:08:33.995 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@38d08310"
19:08:33.995 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0"
19:08:33.996 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:33.996 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 6 ms
19:08:33.997 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
19:08:33.998 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
19:08:33.998 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.NormalFile]
19:08:33.998 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" for datastore
19:08:34.000 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
19:08:34.000 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'"
19:08:34.000 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:34.001 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" ...
19:08:34.009 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@6632fb88"
19:08:34.009 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'
19:08:34.010 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:34.010 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
19:08:34.011 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
19:08:34.012 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
19:08:34.012 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.Directory]
19:08:34.012 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" for datastore
19:08:34.013 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:34.013 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'"
19:08:34.013 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:34.013 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" ...
19:08:34.019 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@e3f94d9"
19:08:34.019 [qtp609375192-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'
19:08:34.020 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:34.021 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 8 ms
19:08:34.021 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
19:08:34.022 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
19:08:34.022 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.CopyModification]
19:08:34.022 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" for datastore
19:08:34.023 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:34.024 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "copymodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'CopyModification'"
19:08:34.024 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:34.024 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" ...
19:08:34.034 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@22e20414"
19:08:34.034 [qtp609375192-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'
19:08:34.035 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:34.035 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 11 ms
19:08:34.036 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
19:08:34.036 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
19:08:34.036 [qtp609375192-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.DeleteModification]
19:08:34.037 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" for datastore
19:08:34.038 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
19:08:34.038 [qtp609375192-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "deletemodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'DeleteModification'"
19:08:34.038 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:34.038 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" ...
19:08:34.050 [qtp609375192-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@20038137"
19:08:34.051 [qtp609375192-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'
19:08:34.051 [qtp609375192-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 14 ms
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:34.052 [qtp609375192-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:34.052 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@1dd4de5
19:08:34.053 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
19:08:34.053 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
19:08:34.053 [qtp609375192-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@11e7005e]]
19:08:34.053 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
19:08:34.053 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
19:08:34.054 [qtp609375192-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d477bc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@38137cd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@1dd4de5" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@4ce81642]"
19:08:34.054 [qtp609375192-38] DEBUG DataNucleus.Transaction - Transaction committed in 2 ms
19:08:34.054 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
19:08:34.054 [qtp609375192-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@1dd4de5" closed
19:08:34.054 [qtp609375192-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4dec6380]getCloseDeferredMillis: closeDeferredMillis=0
19:08:34.054 [qtp609375192-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4dec6380]close: Closing real LocalRepoManager immediately.
19:08:34.054 [qtp609375192-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4dec6380]_close: Shutting down real LocalRepoManager.
19:08:34.266 [qtp609375192-38] DEBUG DataNucleus.Connection - Closing Connection Pool tx
19:08:34.268 [qtp609375192-38] DEBUG DataNucleus.Connection - Closing Connection Pool nontx
19:08:34.268 [qtp609375192-38] DEBUG DataNucleus.Persistence - Unmanaging Persistence of all classes
19:08:34.268 [qtp609375192-38] DEBUG DataNucleus.MetaData - Deregistering listener for metadata initialisation
19:08:34.268 [qtp609375192-38] DEBUG DataNucleus.Cache - Level 2 Cache cleared
19:08:34.541 [qtp609375192-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [38d1fdbc]release: releasing underlying FileLock. lockCounter=0
19:08:34.550 [qtp609375192-38] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@81ea6d1[p=0,l=772,c=8192,r=772]={<<<<?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@275abb90{null}
19:08:34.551 [qtp609375192-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /RepoInfo on HttpChannelOverHttp@617c4285{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:36774/RepoInfo,age=3238}
200 OK HTTP/1.1
Content-Length: 772
Content-Type: application/xml


19:08:34.552 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@3763b6cd[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=772},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@5b6f3fdb] generate: NEED_HEADER (null,[p=0,l=772,c=8192,r=772],true)@START
19:08:34.552 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=200,h=2,cl=772} last=true content=HeapByteBuffer@81ea6d1[p=0,l=772,c=8192,r=772]={<<<<?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}
19:08:34.553 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpGenerator - Content-Length: 772
Content-Type: application/xml


19:08:34.553 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
19:08:34.553 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@3763b6cd[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=772},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@5b6f3fdb] generate: FLUSH ([p=0,l=104,c=8192,r=104],[p=0,l=772,c=8192,r=772],true)@COMPLETING
19:08:34.553 [qtp609375192-38] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@26ba2249{IDLE}->null [HeapByteBuffer@4b29a56a[p=0,l=104,c=8192,r=104]={<<<HTTP/1.1 200 OK\r\n....v20190418)\r\n\r\n>>>ZEaHloOG5iSG4xODV...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@81ea6d1[p=0,l=772,c=8192,r=772]={<<<<?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}]
19:08:34.553 [qtp609375192-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@26ba2249{WRITING}->null:IDLE-->WRITING
19:08:34.554 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 876 SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=W,to=3247/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=END,243 of 243},g=HttpGenerator@12c0ce15{s=COMPLETING}]=>HttpChannelOverHttp@617c4285{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:36774/RepoInfo,age=3241}
19:08:34.554 [qtp609375192-38] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=876 remaining=0 WriteFlusher@26ba2249{WRITING}->null
19:08:34.554 [qtp609375192-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@26ba2249{IDLE}->null:WRITING-->IDLE
19:08:34.554 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@3763b6cd[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=772},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@5b6f3fdb] generate: DONE ([p=104,l=104,c=8192,r=0],[p=772,l=772,c=8192,r=0],true)@END
19:08:34.559 [qtp609375192-38] DEBUG org.eclipse.jetty.server.session - FinalSession=null, old_session_handler=null, this=org.eclipse.jetty.server.session.SessionHandler1070319462==dftMaxIdleSec=-1, calling complete=true
19:08:34.559 [qtp609375192-38] DEBUG org.eclipse.jetty.server.session - Complete called with session null
19:08:34.560 [qtp609375192-38] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@617c4285{r=1,c=true,c=false/true,a=DISPATCHED,uri=//127.0.0.1:36774/RepoInfo,age=3246}
19:08:34.560 [qtp609375192-38] DEBUG o.e.jetty.server.HttpChannelState - unhandle HttpChannelState@6543da72{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
19:08:34.560 [qtp609375192-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@617c4285{r=1,c=true,c=false/true,a=COMPLETING,uri=//127.0.0.1:36774/RepoInfo,age=3247} action COMPLETE
19:08:34.561 [qtp609375192-38] DEBUG o.e.jetty.server.HttpChannelState - onComplete HttpChannelState@6543da72{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
19:08:34.561 [qtp609375192-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /RepoInfo written=772
19:08:34.561 [qtp609375192-38] DEBUG o.e.jetty.server.HttpChannelState - recycle HttpChannelState@6543da72{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
19:08:34.562 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,243 of 243}
19:08:34.562 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
19:08:34.562 [qtp609375192-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
19:08:34.563 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@4b29a56a[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}
19:08:34.563 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@4b29a56a[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}
19:08:34.564 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@4b29a56a[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}
19:08:34.565 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@4b29a56a[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} {}
19:08:34.565 [qtp609375192-38] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@4b29a56a[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}
19:08:34.565 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
19:08:34.565 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.566 [qtp609375192-38] DEBUG o.e.jetty.io.AbstractConnection - fillInterested HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=-,flush=-,to=12/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.566 [qtp609375192-38] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@11abcea2{AC.ReadCB@204148be{HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
19:08:34.566 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.567 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@6ac016af on ManagedSelector@7f0a133d{STARTED} id=1 keys=1 selected=0 updates=0
19:08:34.567 [qtp609375192-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@7f0a133d{STARTED} id=1 keys=1 selected=0 updates=1
19:08:34.567 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken with none selected
19:08:34.567 [qtp609375192-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@6543da72{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
19:08:34.567 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken up from select, 0/0/1 selected
19:08:34.568 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 processing 0 keys, 1 updates
19:08:34.568 [qtp609375192-38] DEBUG o.e.j.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
19:08:34.568 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.568 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@6ac016af
19:08:34.569 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=3/30000}{io=1/1,kio=1,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.569 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.569 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 waiting with 1 keys
19:08:34.572 [main] DEBUG c.c.c.l.r.c.LocalServerRestClient - execute: invocation took 3309 ms
Local repository:
  repository.repositoryId = faf25097-7f0a-4266-84c9-253892721720
  repository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/repo/k98qgde5-4qdl7
  repository.aliases = co.codewizards.cloudstore.ls.core.dto.RepoInfoResponseDto@59b492ec
  repository.publicKeySha1 = 5F:26:EC:CA:2F:E0:C7:B2:90:D7:BE:DE:58:5E:36:05:81:F8:95:C4

Remote repositories connected: {NONE}

Remote repositories requesting connection: {NONE}

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

19:08:34.573 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping Server@12417468{STARTED}[9.4.17.v20190418]
19:08:34.573 [main] DEBUG org.eclipse.jetty.server.Server - doStop Server@12417468{STOPPING}[9.4.17.v20190418]
19:08:34.574 [qtp609375192-35] DEBUG o.e.j.util.thread.QueuedThreadPool - ran qtp609375192-35-acceptor-0@261b9a37-ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:36774}
19:08:34.574 [main] DEBUG o.e.j.s.h.AbstractHandlerContainer - Graceful shutdown Server@12417468{STOPPING}[9.4.17.v20190418] by 
19:08:34.574 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
19:08:34.574 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping SelectorManager@ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
19:08:34.574 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@644a3add{STARTED} id=2 keys=0 selected=0 updates=0
19:08:34.575 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@55c1ced9 on ManagedSelector@644a3add{STOPPING} id=2 keys=0 selected=0 updates=0
19:08:34.575 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@644a3add{STOPPING} id=2 keys=0 selected=0 updates=1
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 woken with none selected
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 woken up from select, 0/0/0 selected
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 processing 0 keys, 1 updates
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@55c1ced9
19:08:34.575 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@644a3add{STOPPING} id=2 keys=0 selected=0 updates=0
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 waiting with 0 keys
19:08:34.576 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@49cc9b2a on ManagedSelector@644a3add{STOPPING} id=2 keys=0 selected=0 updates=0
19:08:34.576 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@644a3add{STOPPING} id=2 keys=0 selected=0 updates=1
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 woken with none selected
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 woken up from select, 0/0/0 selected
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7b30bb19 processing 0 keys, 1 updates
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.576 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@49cc9b2a
19:08:34.577 [qtp609375192-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.577 [qtp609375192-34] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/727860268@622d7e4
19:08:34.577 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@241fbec/SelectorProducer@715fa8c5/IDLE/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-20T19:08:34.577+02:00
19:08:34.577 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@241fbec/SelectorProducer@715fa8c5/IDLE/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-20T19:08:34.577+02:00
19:08:34.578 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@644a3add{STOPPED} id=2 keys=-1 selected=-1 updates=0
19:08:34.578 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@7f0a133d{STARTED} id=1 keys=1 selected=0 updates=0
19:08:34.578 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@11826398 on ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=0
19:08:34.578 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=1
19:08:34.578 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken with none selected
19:08:34.578 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken up from select, 0/0/1 selected
19:08:34.579 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 processing 0 keys, 1 updates
19:08:34.579 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.579 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@11826398
19:08:34.579 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 1 connections on ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=0
19:08:34.580 [qtp609375192-33] DEBUG o.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=13/30000}{io=1/1,kio=1,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.580 [qtp609375192-33] DEBUG o.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,OPEN,fill=FI,flush=-,to=14/30000}{io=1/1,kio=1,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.581 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,CLOSED,fill=FI,flush=-,to=14/30000}{io=1/1,kio=1,kro=1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.582 [qtp609375192-33] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@26ba2249{IDLE}->null
java.nio.channels.ClosedChannelException: null
	at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:492) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:215) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:248) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector.closeNoExceptions(ManagedSelector.java:252) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector.access$1400(ManagedSelector.java:61) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$CloseConnections.update(ManagedSelector.java:866) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.processUpdates(ManagedSelector.java:426) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:397) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:357) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:181) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:786) ~[jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:743) ~[jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_242]
19:08:34.583 [qtp609375192-33] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@11abcea2{AC.ReadCB@204148be{HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,CLOSED,fill=FI,flush=-,to=16/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@204148be[p=HttpParser{s=START,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
19:08:34.583 [qtp609375192-33] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
19:08:34.583 [qtp609375192-33] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
19:08:34.584 [qtp609375192-33] DEBUG o.e.jetty.io.AbstractConnection - HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,CLOSED,fill=-,flush=-,to=17/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@204148be[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed java.nio.channels.ClosedChannelException
19:08:34.584 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=0
19:08:34.584 [qtp609375192-33] DEBUG o.e.j.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@19dd8768
19:08:34.584 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.584 [qtp609375192-39] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@19dd8768
19:08:34.584 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@6c5ca0b6 on ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=0
19:08:34.585 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@7f0a133d{STOPPING} id=1 keys=1 selected=0 updates=1
19:08:34.584 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 waiting with 1 keys
19:08:34.585 [qtp609375192-39] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,CLOSED,fill=-,flush=-,to=19/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@204148be[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.585 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken with none selected
19:08:34.585 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 woken up from select, 0/0/0 selected
19:08:34.585 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@562779e7 processing 0 keys, 1 updates
19:08:34.585 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.585 [qtp609375192-39] DEBUG o.e.jetty.io.AbstractConnection - onClose HttpConnection@204148be::SocketChannelEndPoint@6fbbb102{/127.0.0.1:41154<->/127.0.0.1:36774,CLOSED,fill=-,flush=-,to=19/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@204148be[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@12c0ce15{s=START}]=>HttpChannelOverHttp@617c4285{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
19:08:34.585 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@6c5ca0b6
19:08:34.585 [qtp609375192-39] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@19dd8768
19:08:34.586 [qtp609375192-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.586 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@9df564f/SelectorProducer@1a21f43f/PRODUCING/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-04-20T19:08:34.586+02:00
19:08:34.586 [qtp609375192-33] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/727860268@4a70d302
19:08:34.587 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@9df564f/SelectorProducer@1a21f43f/IDLE/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=6,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2020-04-20T19:08:34.586+02:00
19:08:34.587 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@7f0a133d{STOPPED} id=1 keys=-1 selected=-1 updates=0
19:08:34.587 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@377949f1{STARTED} id=0 keys=0 selected=0 updates=0
19:08:34.587 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@37b01ce2 on ManagedSelector@377949f1{STOPPING} id=0 keys=0 selected=0 updates=0
19:08:34.587 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@377949f1{STOPPING} id=0 keys=0 selected=0 updates=1
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 woken with none selected
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 woken up from select, 0/0/0 selected
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 processing 0 keys, 1 updates
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@37b01ce2
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@377949f1{STOPPING} id=0 keys=0 selected=0 updates=0
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.588 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 waiting with 0 keys
19:08:34.589 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@1a88c4f5 on ManagedSelector@377949f1{STOPPING} id=0 keys=0 selected=0 updates=0
19:08:34.589 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@377949f1{STOPPING} id=0 keys=0 selected=0 updates=1
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 woken with none selected
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 woken up from select, 0/0/0 selected
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@fcaf9a7 processing 0 keys, 1 updates
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@1a88c4f5
19:08:34.589 [qtp609375192-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
19:08:34.589 [qtp609375192-32] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/727860268@4b765e92
19:08:34.590 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@7c5df615/SelectorProducer@2f995afc/IDLE/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-20T19:08:34.59+02:00
19:08:34.591 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@7c5df615/SelectorProducer@2f995afc/IDLE/p=false/QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2020-04-20T19:08:34.59+02:00
19:08:34.591 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@377949f1{STOPPED} id=0 keys=-1 selected=-1 updates=0
19:08:34.591 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED SelectorManager@ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
19:08:34.592 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping HttpConnectionFactory@d325518[HTTP/1.1]
19:08:34.592 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED HttpConnectionFactory@d325518[HTTP/1.1]
19:08:34.592 [main] INFO  o.e.jetty.server.AbstractConnector - Stopped ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
19:08:34.593 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ServerConnector@2233cac0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
19:08:34.593 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping Server@12417468{STOPPING}[9.4.17.v20190418]
19:08:34.593 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping DefaultSessionIdManager@2e1eb85f{STARTED}[worker=node0]
19:08:34.593 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping HouseKeeper@ea7a4c3{STARTED}[interval=660000, ownscheduler=false]
19:08:34.593 [main] INFO  org.eclipse.jetty.server.session - node0 Stopped scavenging
19:08:34.593 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED HouseKeeper@ea7a4c3{STOPPED}[interval=660000, ownscheduler=false]
19:08:34.594 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED DefaultSessionIdManager@2e1eb85f{STOPPED}[worker=node0]
19:08:34.594 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ErrorHandler@7dee835{STARTED}
19:08:34.594 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping ErrorHandler@7dee835{STOPPING}
19:08:34.594 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ErrorHandler@7dee835{STOPPED}
19:08:34.594 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping o.e.j.s.ServletContextHandler@392781e{/,null,SHUTDOWN}
19:08:34.595 [main] DEBUG o.e.j.s.h.AbstractHandlerContainer - Graceful shutdown o.e.j.s.ServletContextHandler@392781e{/,null,SHUTDOWN} by 
19:08:34.595 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping o.e.j.s.ServletContextHandler@392781e{/,null,UNAVAILABLE}
19:08:34.595 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.SessionHandler1070319462==dftMaxIdleSec=-1
19:08:34.596 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.DefaultSessionCache@2a8b33ba[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
19:08:34.596 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.NullSessionDataStore@4e8b357d[passivating=false,graceSec=3600]
19:08:34.596 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.NullSessionDataStore@4e8b357d[passivating=false,graceSec=3600]
19:08:34.596 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.DefaultSessionCache@2a8b33ba[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
19:08:34.597 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.session.SessionHandler1070319462==dftMaxIdleSec=-1
19:08:34.597 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ServletHandler@28cd2c2{STARTED}
19:08:34.597 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping ServletHandler@28cd2c2{STOPPING}
19:08:34.597 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-6af5b246@30b2fe0==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
19:08:34.598 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-6af5b246@30b2fe0==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
19:08:34.598 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.glassfish.jersey.servlet.ServletContainer-1d4f5506@c3758af9==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
19:08:34.598 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.glassfish.jersey.servlet.ServletContainer-1d4f5506@c3758af9==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
19:08:34.598 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ServletHandler@28cd2c2{STOPPED}
19:08:34.599 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.SessionHandler1070319462==dftMaxIdleSec=-1
19:08:34.599 [main] INFO  o.e.j.server.handler.ContextHandler - Stopped o.e.j.s.ServletContextHandler@392781e{/,null,UNAVAILABLE}
19:08:34.599 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED o.e.j.s.ServletContextHandler@392781e{/,null,UNAVAILABLE}
19:08:34.600 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@459003a0{STARTED}
19:08:34.600 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@459003a0{STOPPED}
19:08:34.600 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping QueuedThreadPool[qtp609375192]@245253d8{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}]
19:08:34.600 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Stopping QueuedThreadPool[qtp609375192]@245253d8{STOPPING,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@1b57c345{s=1/6,p=0}]
19:08:34.601 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ReservedThreadExecutor@1b57c345{s=1/6,p=0}
19:08:34.601 [main] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@1b57c345{s=0/6,p=0}@5cdf1297 offer STOP!
19:08:34.601 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ReservedThreadExecutor@1b57c345{s=0/6,p=0}
19:08:34.601 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp609375192-39,5,main] for 14999
19:08:34.601 [qtp609375192-37] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@1b57c345{s=0/6,p=0}@5cdf1297 task=STOP!
19:08:34.601 [qtp609375192-36] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.601 [qtp609375192-36] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.601 [qtp609375192-37] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@1b57c345{s=0/6,p=0}@5cdf1297 Exited
19:08:34.601 [qtp609375192-38] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.601 [qtp609375192-37] DEBUG o.e.j.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@1b57c345{s=0/6,p=0}@5cdf1297
19:08:34.601 [qtp609375192-38] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-35] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-35] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-34] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-34] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-39] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-39] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-33] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-32] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-33] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [qtp609375192-32] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$52/384887832@4d39cab2
19:08:34.602 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp609375192-36,5,] for 14998
19:08:34.603 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED QueuedThreadPool[qtp609375192]@245253d8{STOPPED,8<=0<=500,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/2044903525@4218d6a3]
19:08:34.603 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED Server@12417468{STOPPED}[9.4.17.v20190418]
19:08:34.603 [main] INFO  c.c.cloudstore.ls.server.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/.cloudstore/localServerRunning.deleteToStop
19:08:34.604 [main] DEBUG c.c.cloudstore.core.io.LockFileImpl - [385dfb63]release: releasing underlying FileLock. lockCounter=0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.885 sec - in co.codewizards.cloudstore.client.RepairDatabaseSubCommandTest

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_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT.jar
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy-dependencies (copy-dependencies) @ co.codewizards.cloudstore.client ---
[INFO] Copying jaxb-core-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jaxb-core-2.3.0.jar
[INFO] Copying jetty-util-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/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_8/co.codewizards.cloudstore.client/target/dependency/jetty-servlet-9.4.17.v20190418.jar
[INFO] Copying jersey-container-servlet-core-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jersey-container-servlet-core-2.5.1.jar
[INFO] Copying transaction-api-1.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/transaction-api-1.1.jar
[INFO] Copying jetty-continuation-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jetty-continuation-9.4.17.v20190418.jar
[INFO] Copying co.codewizards.cloudstore.updater-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.updater-0.10.8-SNAPSHOT.jar
[INFO] Copying bcprov-jdk15on-161b03.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/bcprov-jdk15on-161b03.jar
[INFO] Copying cglib-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/cglib-2.2.0-b21.jar
[INFO] Copying jdo-api-3.1-rc1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jdo-api-3.1-rc1.jar
[INFO] Copying jaxb-api-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jaxb-api-2.3.0.jar
[INFO] Copying jetty-http-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jetty-http-9.4.17.v20190418.jar
[INFO] Copying jersey-server-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jersey-server-2.5.1.jar
[INFO] Copying jetty-security-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jetty-security-9.4.17.v20190418.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.client-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.client-0.10.8-SNAPSHOT.jar
[INFO] Copying args4j-2.0.16.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/args4j-2.0.16.jar
[INFO] Copying jersey-container-servlet-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jersey-container-servlet-2.5.1.jar
[INFO] Copying hk2-api-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/hk2-api-2.2.0-b21.jar
[INFO] Copying co.codewizards.cloudstore.rest.client-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.client-0.10.8-SNAPSHOT.jar
[INFO] Copying javax.inject-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/javax.inject-2.2.0-b21.jar
[INFO] Copying co.codewizards.cloudstore.core-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core-0.10.8-SNAPSHOT.jar
[INFO] Copying datanucleus-api-jdo-4.0.5.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/datanucleus-api-jdo-4.0.5.jar
[INFO] Copying validation-api-1.1.0.Final.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/validation-api-1.1.0.Final.jar
[INFO] Copying co.codewizards.cloudstore.core.oio.nio-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core.oio.nio-0.10.8-SNAPSHOT.jar
[INFO] Copying log4j-over-slf4j-1.7.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/log4j-over-slf4j-1.7.7.jar
[INFO] Copying derby-10.13.1.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/derby-10.13.1.1.jar
[INFO] Copying asm-all-repackaged-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/asm-all-repackaged-2.2.0-b21.jar
[INFO] Copying hk2-utils-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/hk2-utils-2.2.0-b21.jar
[INFO] Copying commons-compress-1.18.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/commons-compress-1.18.jar
[INFO] Copying hk2-locator-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/hk2-locator-2.2.0-b21.jar
[INFO] Copying jetty-servlets-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/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_8/co.codewizards.cloudstore.client/target/dependency/jetty-server-9.4.17.v20190418.jar
[INFO] Copying javax.ws.rs-api-2.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/javax.ws.rs-api-2.0.jar
[INFO] Copying co.codewizards.cloudstore.ls.server-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server-0.10.8-SNAPSHOT.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.server-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.server-0.10.8-SNAPSHOT.jar
[INFO] Copying co.codewizards.cloudstore.ls.server.cproc-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server.cproc-0.10.8-SNAPSHOT.jar
[INFO] Copying activation-1.1.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/activation-1.1.1.jar
[INFO] Copying guava-14.0.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/guava-14.0.1.jar
[INFO] Copying co.codewizards.cloudstore.ls.core-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.core-0.10.8-SNAPSHOT.jar
[INFO] Copying javax.annotation-api-1.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/javax.annotation-api-1.2.jar
[INFO] Copying co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar
[INFO] Copying jersey-common-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jersey-common-2.5.1.jar
[INFO] Copying datanucleus-rdbms-4.0.12.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/datanucleus-rdbms-4.0.12.jar
[INFO] Copying datanucleus-core-4.0.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/datanucleus-core-4.0.7.jar
[INFO] Copying osgi-resource-locator-1.0.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/osgi-resource-locator-1.0.1.jar
[INFO] Copying javax.servlet-api-3.1.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/javax.servlet-api-3.1.0.jar
[INFO] Copying jetty-xml-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jetty-xml-9.4.17.v20190418.jar
[INFO] Copying logback-core-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/logback-core-1.0.13.jar
[INFO] Copying jaxb-impl-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jaxb-impl-2.3.0.jar
[INFO] Copying logback-classic-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/logback-classic-1.0.13.jar
[INFO] Copying jersey-client-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jersey-client-2.5.1.jar
[INFO] Copying co.codewizards.cloudstore.rest.shared-0.10.8-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.shared-0.10.8-SNAPSHOT.jar
[INFO] Copying jetty-io-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/jetty-io-9.4.17.v20190418.jar
[INFO] Copying slf4j-api-1.7.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/dependency/slf4j-api-1.7.7.jar
[INFO] Copying jetty-webapp-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/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_8/co.codewizards.cloudstore.client/target/dependency/bcpg-jdk15on-161b03.jar
[INFO] 
[INFO] --- maven-assembly-plugin:2.4: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_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.tar.gz
[INFO] Building zip: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.zip
[INFO] 
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.client ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ co.codewizards.cloudstore.client ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.tar.gz to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.tar.gz
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.zip to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-bin.zip
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.client ---
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml (2 KB at 708.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2.jar (44 KB at 21834.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2.pom
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2.pom (4 KB at 3702.1 KB/sec)
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (358 B at 349.6 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml (2 KB at 1416.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (358 B)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-bin.tar.gz
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-bin.tar.gz (21663 KB at 53753.9 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml (2 KB at 1416.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-bin.zip
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-bin.zip (23980 KB at 53288.7 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml (2 KB at 1416.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-sources.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/co.codewizards.cloudstore.client-0.10.8-20200420.170839-2-sources.jar (26 KB at 25951.2 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.8-SNAPSHOT/maven-metadata.xml (2 KB at 1416.0 KB/sec)
[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_8/co.codewizards.cloudstore.client/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore_java_8/site/co.codewizards.cloudstore.client
[INFO] 
[INFO] >>> maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) > generate-sources @ co.codewizards.cloudstore.client >>>
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO] 
[INFO] <<< maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) < generate-sources @ co.codewizards.cloudstore.client <<<
[INFO] 
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.client ---
[INFO] 
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.client ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[INFO] 
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.client ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/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_8/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