Console Output

6149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co.jdo
17:07:39.356 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.jdo
17:07:39.356 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards.jdo
17:07:39.357 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.jdo
17:07:39.358 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore.jdo
17:07:39.359 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/package.jdo
17:07:39.360 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local.jdo
17:07:39.360 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.361 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
17:07:39.362 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.362 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
17:07:39.366 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" has been specified with JDO annotations so using those.
17:07:39.368 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Populating Meta-Data
17:07:39.369 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /META-INF/package.orm
17:07:39.369 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /WEB-INF/package.orm
17:07:39.370 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /package.orm
17:07:39.371 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/package.orm
17:07:39.371 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co.orm
17:07:39.372 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/package.orm
17:07:39.372 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards.orm
17:07:39.373 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/package.orm
17:07:39.374 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore.orm
17:07:39.375 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/package.orm
17:07:39.376 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local.orm
17:07:39.376 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.377 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" NOT found at /co/codewizards/cloudstore/local/persistence.orm
17:07:39.378 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.378 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" not found
17:07:39.378 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.FileInProgressMarker" : Initialising Meta-Data
17:07:39.379 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.FileInProgressMarker [Table : "fileinprogressmarker", InheritanceStrategy : new-table]
17:07:39.380 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."id"" added to internal representation of table.
17:07:39.380 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.380 [qtp1626149608-44 - /RepoInfo] 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") 
17:07:39.380 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."fromrepositoryid"" added to internal representation of table.
17:07:39.381 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.381 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."path"" added to internal representation of table.
17:07:39.381 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.381 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."pathsha1"" added to internal representation of table.
17:07:39.381 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.382 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."torepositoryid"" added to internal representation of table.
17:07:39.382 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.382 [qtp1626149608-44 - /RepoInfo] 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") 
17:07:39.382 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."changed"" added to internal representation of table.
17:07:39.382 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.382 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""fileinprogressmarker"."created"" added to internal representation of table.
17:07:39.382 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.382 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Table/View "fileinprogressmarker" has been initialised
17:07:39.383 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5c04534b [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
17:07:39.383 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@5b8431e" with isolation "serializable"
17:07:39.388 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Check of existence of "fileinprogressmarker" returned table type of TABLE
17:07:39.388 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "fileinprogressmarker, copymodification, lastsynctoremoterepo, remoterepositoryrequest, localrepository_aliases, repofile, deletemodification, repository, modification, filechunk" in Catalog "", Schema ""
17:07:39.477 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 10 tables, time = 89 ms
17:07:39.477 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "fileinprogressmarker" : 7 columns found
17:07:39.486 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "fileinprogressmarker"
17:07:39.486 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Creating index "FileInProgressMark_fromRepositoryId_toRepositoryId" in catalog "" schema ""
17:07:39.486 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid")
17:07:39.511 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Execution Time = 25 ms
17:07:39.538 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "fileinprogressmarker"
17:07:39.554 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "fileinprogressmarker"
17:07:39.555 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Creating candidate key "FileInProgressMark_fromRepositoryId_toRepositoryId_pathSha1" in catalog "" schema ""
17:07:39.555 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "fileinprogressmark_fromrepositoryid_torepositoryid_pathsha1" ON "fileinprogressmarker" ("fromrepositoryid","torepositoryid","pathsha1")
17:07:39.579 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Execution Time = 24 ms
17:07:39.579 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@5b8431e"
17:07:39.585 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@5b8431e"
17:07:39.585 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5c04534b [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@5b8431e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
17:07:39.585 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5c04534b [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@5b8431e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
17:07:39.587 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.jdo
17:07:39.587 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.jdo
17:07:39.588 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.jdo
17:07:39.589 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.jdo
17:07:39.590 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co.jdo
17:07:39.590 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.jdo
17:07:39.591 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards.jdo
17:07:39.592 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.jdo
17:07:39.593 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore.jdo
17:07:39.594 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/package.jdo
17:07:39.595 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local.jdo
17:07:39.595 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.596 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
17:07:39.597 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.597 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
17:07:39.597 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" has been specified with JDO annotations so using those.
17:07:39.598 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Populating Meta-Data
17:07:39.599 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.orm
17:07:39.599 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.orm
17:07:39.600 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.orm
17:07:39.601 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.orm
17:07:39.602 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co.orm
17:07:39.602 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.orm
17:07:39.603 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards.orm
17:07:39.604 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.orm
17:07:39.604 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore.orm
17:07:39.605 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/package.orm
17:07:39.606 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local.orm
17:07:39.607 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.608 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence.orm
17:07:39.608 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.608 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
17:07:39.609 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Initialising Meta-Data
17:07:39.609 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.Symlink [Table : "repofile", InheritanceStrategy : superclass-table]
17:07:39.610 [qtp1626149608-44 - /RepoInfo] 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") 
17:07:39.610 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""repofile"."target"" added to internal representation of table.
17:07:39.610 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.610 [qtp1626149608-44 - /RepoInfo] 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)
17:07:39.611 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37b76f79 [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
17:07:39.611 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@32dd368e" with isolation "serializable"
17:07:39.617 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repofile" returned table type of TABLE
17:07:39.625 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 6 index(es) for table "repofile"
17:07:39.646 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 1 foreign key(s) for table "repofile"
17:07:39.649 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repofile"
17:07:39.649 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@32dd368e"
17:07:39.649 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@32dd368e"
17:07:39.650 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37b76f79 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@32dd368e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
17:07:39.650 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@37b76f79 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@32dd368e, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
17:07:39.651 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /META-INF/package.jdo
17:07:39.651 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /WEB-INF/package.jdo
17:07:39.652 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /package.jdo
17:07:39.653 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/package.jdo
17:07:39.653 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co.jdo
17:07:39.654 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/package.jdo
17:07:39.655 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards.jdo
17:07:39.655 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/package.jdo
17:07:39.656 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore.jdo
17:07:39.657 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/package.jdo
17:07:39.658 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local.jdo
17:07:39.658 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence/package.jdo
17:07:39.659 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
17:07:39.661 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence/TransferDoneMarker.jdo
17:07:39.661 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" not found
17:07:39.662 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" has been specified with JDO annotations so using those.
17:07:39.662 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" : Populating Meta-Data
17:07:39.663 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /META-INF/package.orm
17:07:39.664 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /WEB-INF/package.orm
17:07:39.665 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /package.orm
17:07:39.665 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/package.orm
17:07:39.666 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co.orm
17:07:39.667 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/package.orm
17:07:39.667 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards.orm
17:07:39.668 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/package.orm
17:07:39.669 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore.orm
17:07:39.670 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/package.orm
17:07:39.670 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local.orm
17:07:39.671 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence/package.orm
17:07:39.672 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence.orm
17:07:39.673 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence/TransferDoneMarker.orm
17:07:39.673 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" not found
17:07:39.673 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" field "fromEntityId" : Adding Meta-Data for field since it didnt appear in the Meta-Data definition.
17:07:39.673 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" field "fromLocalRevision" : Adding Meta-Data for field since it didnt appear in the Meta-Data definition.
17:07:39.673 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Java type co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType using the support for java.lang.Enum
17:07:39.674 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /META-INF/package.jdo
17:07:39.675 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /WEB-INF/package.jdo
17:07:39.675 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /package.jdo
17:07:39.676 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/package.jdo
17:07:39.677 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co.jdo
17:07:39.677 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/package.jdo
17:07:39.678 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards.jdo
17:07:39.679 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/package.jdo
17:07:39.680 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore.jdo
17:07:39.681 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/package.jdo
17:07:39.682 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core.jdo
17:07:39.683 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/repo/package.jdo
17:07:39.684 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/repo.jdo
17:07:39.684 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/repo/transport/package.jdo
17:07:39.687 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/repo/transport.jdo
17:07:39.687 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core/repo/transport/TransferDoneMarkerType.jdo
17:07:39.688 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" not found
17:07:39.688 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Registering class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" as not having MetaData.
17:07:39.688 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" : Initialising Meta-Data
17:07:39.689 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.TransferDoneMarker [Table : "transferdonemarker", InheritanceStrategy : new-table]
17:07:39.690 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."id"" added to internal representation of table.
17:07:39.691 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.id] -> Column(s) ["transferdonemarker"."id"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.LongMapping" (org.datanucleus.store.rdbms.mapping.datastore.BigIntRDBMSMapping)
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Table "transferdonemarker" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.TransferDoneMarker (inheritance strategy="new-table") 
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromentityid"" added to internal representation of table.
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.TransferDoneMarker.fromEntityId] -> Column(s) ["transferdonemarker"."fromentityid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.LongMapping" (org.datanucleus.store.rdbms.mapping.datastore.BigIntRDBMSMapping)
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromlocalrevision"" added to internal representation of table.
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.TransferDoneMarker.fromLocalRevision] -> Column(s) ["transferdonemarker"."fromlocalrevision"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.LongMapping" (org.datanucleus.store.rdbms.mapping.datastore.BigIntRDBMSMapping)
17:07:39.692 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromrepositoryid"" added to internal representation of table.
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.TransferDoneMarker.fromRepositoryId] -> Column(s) ["transferdonemarker"."fromrepositoryid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.datastore.VarCharRDBMSMapping)
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."torepositoryid"" added to internal representation of table.
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.TransferDoneMarker.toRepositoryId] -> Column(s) ["transferdonemarker"."torepositoryid"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.datastore.VarCharRDBMSMapping)
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."transferdonemarkertype"" added to internal representation of table.
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.TransferDoneMarker.transferDoneMarkerType] -> Column(s) ["transferdonemarker"."transferdonemarkertype"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.EnumMapping" (org.datanucleus.store.rdbms.mapping.datastore.IntegerRDBMSMapping)
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Table "transferdonemarker" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.Entity (inheritance strategy="subclass-table") 
17:07:39.693 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."changed"" added to internal representation of table.
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.changed] -> Column(s) ["transferdonemarker"."changed"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.datastore.TimestampRDBMSMapping)
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."created"" added to internal representation of table.
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Entity.created] -> Column(s) ["transferdonemarker"."created"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.DateMapping" (org.datanucleus.store.rdbms.mapping.datastore.TimestampRDBMSMapping)
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Table/View "transferdonemarker" has been initialised
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3b94785 [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
17:07:39.694 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@6ddcf5cc" with isolation "serializable"
17:07:39.695 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Check of existence of "transferdonemarker" returned table type of TABLE
17:07:39.696 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Loading column info for table(s) "fileinprogressmarker, copymodification, lastsynctoremoterepo, remoterepositoryrequest, localrepository_aliases, repofile, transferdonemarker, deletemodification, repository, modification, filechunk" in Catalog "", Schema ""
17:07:39.722 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 11 tables, time = 27 ms
17:07:39.722 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "transferdonemarker" : 8 columns found
17:07:39.724 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "transferdonemarker"
17:07:39.724 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Creating index "TransferDoneMarker_fromRepositoryId_toRepositoryId" in catalog "" schema ""
17:07:39.724 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "transferdonemarker_fromrepositoryid_torepositoryid" ON "transferdonemarker" ("fromrepositoryid","torepositoryid")
17:07:39.755 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Execution Time = 31 ms
17:07:39.788 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "transferdonemarker"
17:07:39.811 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "transferdonemarker"
17:07:39.811 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Creating candidate key "TransferDoneMarker_fromRepositoryId_toRepositoryId_transferDoneMarkerType_fromEntityId" in catalog "" schema ""
17:07:39.811 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "transferdonemarker_fromrepositoryid_torepositoryid_transferdonemarkertype_fromentityid" ON "transferdonemarker" ("fromrepositoryid","torepositoryid","transferdonemarkertype","fromentityid")
17:07:39.839 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Execution Time = 28 ms
17:07:39.839 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@6ddcf5cc"
17:07:39.845 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@6ddcf5cc"
17:07:39.846 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3b94785 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@6ddcf5cc, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
17:07:39.846 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@3b94785 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@6ddcf5cc, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
17:07:39.846 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:39.846 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:39.847 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@1facf75b" with txn="org.datanucleus.TransactionImpl@18ed1af0"
17:07:39.847 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.847 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3 (optimistic=false)
17:07:39.848 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
17:07:39.848 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
17:07:39.848 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.LocalRepository]
17:07:39.848 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" for datastore
17:07:39.850 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
17:07:39.850 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:39.852 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
17:07:39.852 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@2bdf3879, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.852 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
17:07:39.853 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.853 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
17:07:39.863 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@36e592e6"
17:07:39.863 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
17:07:39.864 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.864 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 11 ms
17:07:39.865 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
17:07:39.865 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 2 cache
17:07:39.865 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
17:07:39.866 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="1") added to Level 2 cache (fields="[0, 1, 2, 4, 5]", version="")
17:07:39.867 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "HOLLOW"->"P_CLEAN"
17:07:39.867 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="1") enlisted in transactional cache
17:07:39.868 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" having fields "publicKey" fetched from table ""repository""
17:07:39.868 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.876 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@18ee457a"
17:07:39.876 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."publickey" FROM "repository" "a0" WHERE "a0"."id" = <1>
17:07:39.877 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
17:07:39.877 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@585a86e7"
17:07:39.877 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="1") is having the following fields in Level 2 cache object updated : [3]
17:07:39.878 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" having fields "privateKey" fetched from table ""repository""
17:07:39.879 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.884 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@6f7b87c7"
17:07:39.884 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."privatekey" FROM "repository" "a0" WHERE "a0"."id" = <1>
17:07:39.885 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.885 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@6d7175a4"
17:07:39.885 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="1") is having the following fields in Level 2 cache object updated : [7]
17:07:39.886 [qtp1626149608-44 - /RepoInfo] INFO  c.c.c.local.LocalRepoManagerImpl - [72e8ddd]initPersistenceManagerFactory: repositoryId=9a97329a-3110-47a7-8e10-b5cb6a534d65
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@2bdf3879]]
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
17:07:39.886 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@327acb78 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@170c48a3, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@409a4e5c, lifecycle=HOLLOW]
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:39.887 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:39.888 [qtp1626149608-44 - /RepoInfo] INFO  c.c.c.local.LocalRepoManagerImpl - [72e8ddd]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2649 ms.
17:07:39.888 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@1facf75b" with txn="org.datanucleus.TransactionImpl@3731b024"
17:07:39.888 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.888 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3 (optimistic=false)
17:07:39.888 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Single-String with "SELECT WHERE this.changed < :changed"
17:07:39.889 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
17:07:39.890 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
17:07:39.890 [qtp1626149608-44 - /RepoInfo] 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]
17:07:39.890 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" for datastore
17:07:39.891 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
17:07:39.892 [qtp1626149608-44 - /RepoInfo] 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" < ?"
17:07:39.892 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
17:07:39.892 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@72d58b02, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.892 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@34385a19, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
17:07:39.892 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@34385a19, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.892 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" ...
17:07:39.900 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@64f68f96"
17:07:39.901 [qtp1626149608-44 - /RepoInfo] 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" < <2017-10-13 17:07:39.888>
17:07:39.902 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.902 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@72d58b02]]
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@34385a19, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@34385a19, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
17:07:39.903 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@485fa2bd [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@34385a19, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [543508af]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [543508af]tryAcquire: returning true. lockCounter=1
17:07:39.904 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [543508af]tryAcquire: returning true. lockCounter=2
17:07:39.905 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
17:07:39.905 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [543508af]release: NOT releasing underlying FileLock. lockCounter=1
17:07:39.906 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [543508af]release: releasing underlying FileLock. lockCounter=0
17:07:39.906 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@1facf75b" with txn="org.datanucleus.TransactionImpl@3abc2b8c"
17:07:39.907 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
17:07:39.907 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3 (optimistic=false)
17:07:39.907 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.907 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.908 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
17:07:39.908 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@34e026ed, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
17:07:39.908 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=
" with flags "0"
17:07:39.908 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.908 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
17:07:39.909 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@143ab0bd"
17:07:39.909 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
17:07:39.910 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.910 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 2 ms
17:07:39.910 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
17:07:39.910 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
17:07:39.911 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
17:07:39.911 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
17:07:39.911 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="1") enlisted in transactional cache
17:07:39.911 [qtp1626149608-44 - /RepoInfo] 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@34a81bc5"
17:07:39.912 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, queued-operations=false, allow-nulls=false]
17:07:39.912 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="1") is having the following fields in Level 2 cache object updated : [6]
17:07:39.913 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" field "aliases" loading contents to SCO wrapper from the datastore
17:07:39.913 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.923 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@34d89685"
17:07:39.923 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
17:07:39.924 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.924 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@3282c4ec"
17:07:39.924 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [68dc7b83]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
17:07:39.925 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [68dc7b83]tryAcquire: returning true. lockCounter=1
17:07:39.925 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [68dc7b83]release: releasing underlying FileLock. lockCounter=0
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.local.LocalRepoManagerImpl - syncWithLocalRepoRegistry: Alias 'j8rgh3bq-b0rd1' of repository '9a97329a-3110-47a7-8e10-b5cb6a534d65' already in-sync.
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8624fd1]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8624fd1]tryAcquire: returning true. lockCounter=1
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8624fd1]tryAcquire: returning true. lockCounter=2
17:07:39.926 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8624fd1]release: NOT releasing underlying FileLock. lockCounter=1
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [8624fd1]release: releasing underlying FileLock. lockCounter=0
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3
17:07:39.927 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=
, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@34e026ed]]
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=
" with onePhase="true"
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@e6d3140 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@128cd52f, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
17:07:39.928 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@34a81bc5, lifecycle=HOLLOW]
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@1facf75b" with txn="org.datanucleus.TransactionImpl@3a10e41b"
17:07:39.929 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.930 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3 (optimistic=false)
17:07:39.930 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.930 [qtp1626149608-44 - /RepoInfo] 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
17:07:39.930 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5c369ff9, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@650d6819"
17:07:39.931 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
17:07:39.932 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.932 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
17:07:39.932 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
17:07:39.932 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
17:07:39.933 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
17:07:39.933 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
17:07:39.933 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="1") enlisted in transactional cache
17:07:39.934 [qtp1626149608-44 - /RepoInfo] 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@4c860098"
17:07:39.934 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, queued-operations=false, allow-nulls=false]
17:07:39.934 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="1") is having the following fields in Level 2 cache object updated : [6]
17:07:39.934 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.946 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@36e47d51"
17:07:39.946 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT COUNT(*) FROM "localrepository_aliases" THIS WHERE THIS."id_oid"=<1>
17:07:39.947 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.947 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@3887f1a1"
17:07:39.947 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" field "aliases" loading contents to SCO wrapper from the datastore
17:07:39.948 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.948 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@41a0a175"
17:07:39.949 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
17:07:39.949 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
17:07:39.949 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@496c6984"
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5c369ff9]]
17:07:39.950 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4d738248 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@30c4a6fc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@4c860098, lifecycle=HOLLOW]
17:07:39.951 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
17:07:39.952 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:39.952 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:39.952 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.local.LocalRepoManagerImpl - [72e8ddd]open: closing=false closeAbortable=true
17:07:39.953 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@1facf75b" with txn="org.datanucleus.TransactionImpl@a0e3841"
17:07:39.953 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.953 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3 (optimistic=false)
17:07:39.953 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6c1a66e9]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
17:07:39.954 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6c1a66e9]tryAcquire: returning true. lockCounter=1
17:07:39.954 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6c1a66e9]tryAcquire: returning true. lockCounter=2
17:07:39.954 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
17:07:39.954 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6c1a66e9]release: NOT releasing underlying FileLock. lockCounter=1
17:07:39.954 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6c1a66e9]release: releasing underlying FileLock. lockCounter=0
17:07:39.955 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
17:07:39.956 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
17:07:39.956 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepository]
17:07:39.956 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" for datastore
17:07:39.958 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
17:07:39.958 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:39.959 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
17:07:39.959 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5186ec89, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
17:07:39.959 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
17:07:39.959 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.959 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" ...
17:07:39.967 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@1e9a1559"
17:07:39.968 [qtp1626149608-44 - /RepoInfo] 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'
17:07:39.968 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
17:07:39.969 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
17:07:39.970 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
17:07:39.970 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
17:07:39.970 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest]
17:07:39.970 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" for datastore
17:07:39.971 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
17:07:39.971 [qtp1626149608-44 - /RepoInfo] 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""
17:07:39.972 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.972 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" ...
17:07:39.977 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@1149b6f9"
17:07:39.978 [qtp1626149608-44 - /RepoInfo] 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"
17:07:39.978 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
17:07:39.978 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 6 ms
17:07:39.979 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
17:07:39.980 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
17:07:39.980 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.NormalFile]
17:07:39.980 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" for datastore
17:07:39.982 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
17:07:39.983 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:39.983 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.983 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" ...
17:07:39.992 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@56121f81"
17:07:39.992 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'
17:07:39.993 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:39.993 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 10 ms
17:07:39.994 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
17:07:39.995 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
17:07:39.995 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.Directory]
17:07:39.995 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" for datastore
17:07:39.996 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
17:07:39.996 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:39.997 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:39.997 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" ...
17:07:40.004 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@41b0e689"
17:07:40.005 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'
17:07:40.006 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:40.006 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
17:07:40.007 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
17:07:40.008 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
17:07:40.008 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.CopyModification]
17:07:40.008 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" for datastore
17:07:40.009 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
17:07:40.010 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:40.010 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:40.010 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" ...
17:07:40.023 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@610a9dd6"
17:07:40.023 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "copymodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'CopyModification'
17:07:40.024 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:40.024 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 14 ms
17:07:40.025 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
17:07:40.026 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
17:07:40.026 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.DeleteModification]
17:07:40.026 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" for datastore
17:07:40.027 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
17:07:40.027 [qtp1626149608-44 - /RepoInfo] 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'"
17:07:40.028 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:40.028 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" ...
17:07:40.039 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@3f3b78d8"
17:07:40.039 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "deletemodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'DeleteModification'
17:07:40.040 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 12 ms
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:40.041 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@419873d3
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5186ec89]]
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
17:07:40.042 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@6e94e29c [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@2ae8acfd, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@419873d3" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@5f32bdaf]"
17:07:40.043 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
17:07:40.043 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 1 Cache cleared
17:07:40.043 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@419873d3" closed
17:07:40.043 [qtp1626149608-44 - /RepoInfo] INFO  c.c.c.local.LocalRepoManagerImpl - [72e8ddd]_close: Shutting down real LocalRepoManager.
17:07:40.292 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - Closing Connection Pool tx
17:07:40.296 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Connection - Closing Connection Pool nontx
17:07:40.297 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Persistence - Unmanaging Persistence of all classes
17:07:40.298 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.MetaData - Deregistering listener for metadata initialisation
17:07:40.300 [qtp1626149608-44 - /RepoInfo] DEBUG DataNucleus.Cache - Level 2 Cache cleared
17:07:40.580 [qtp1626149608-44 - /RepoInfo] DEBUG c.c.cloudstore.core.io.LockFileImpl - [b67a41d]release: releasing underlying FileLock. lockCounter=0
17:07:40.587 [qtp1626149608-44 - /RepoInfo] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$CommitCallback@2c2b9273[ITERATING] generate: NEED_HEADER (null,[p=0,l=765,c=8192,r=765],true)@START
17:07:40.588 [qtp1626149608-44 - /RepoInfo] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$CommitCallback@2c2b9273[ITERATING] generate: FLUSH ([p=0,l=103,c=8192,r=103],[p=0,l=765,c=8192,r=765],true)@COMPLETING
17:07:40.588 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@3f829415{IDLE} [HeapByteBuffer@657c051a[p=0,l=103,c=8192,r=103]={<<<HTTP/1.1 200 OK||....v20140505)||||>>>�����������������...���������������},HeapByteBuffer@7bdba5d7[p=0,l=765,c=8192,r=765]={<<<<?xml version="1....nfoResponseDto>>>>�����������������...���������������}]
17:07:40.588 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3f829415{WRITING}:IDLE-->WRITING
17:07:40.589 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 868 SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,Open,in,out,-,W,30000,HttpConnection}{io=0,kio=0,kro=1}
17:07:40.589 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.io.WriteFlusher - flushed true
17:07:40.589 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3f829415{IDLE}:WRITING-->IDLE
17:07:40.589 [qtp1626149608-44 - /RepoInfo] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$CommitCallback@2c2b9273[ITERATING] generate: DONE ([p=103,l=103,c=8192,r=0],[p=765,l=765,c=8192,r=0],true)@END
17:07:40.595 [qtp1626149608-44 - /RepoInfo] DEBUG org.eclipse.jetty.server.Server - RESPONSE /RepoInfo  200 handled=true
17:07:40.595 [qtp1626149608-44 - /RepoInfo] DEBUG o.e.jetty.server.HttpChannelState - HttpChannelState@3a78d196{s=DISPATCHED i=true a=null} unhandle DISPATCHED
17:07:40.595 [qtp1626149608-44] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,236 of 236}
17:07:40.595 [qtp1626149608-44] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
17:07:40.595 [qtp1626149608-44] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@3080bae6{r=1,a=IDLE,uri=-} handle exit, result COMPLETE
17:07:40.596 [qtp1626149608-44] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,Open,in,out,-,-,30000,HttpConnection}{io=0,kio=0,kro=1}
17:07:40.596 [qtp1626149608-44] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,Open,in,out,-,-,30000,HttpConnection}{io=0,kio=0,kro=1}
17:07:40.596 [qtp1626149608-44] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@657c051a[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK||...���������������}
17:07:40.596 [qtp1626149608-44] DEBUG o.e.jetty.io.AbstractConnection - fillInterested HttpConnection@4337dd30{FILLING}
17:07:40.596 [qtp1626149608-44] DEBUG o.e.jetty.io.AbstractConnection - FILLING-->FILLING_FILL_INTERESTED HttpConnection@4337dd30{FILLING_FILL_INTERESTED}
17:07:40.597 [qtp1626149608-44] DEBUG o.e.jetty.io.AbstractConnection - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@4337dd30{FILL_INTERESTED}
17:07:40.597 [qtp1626149608-44] DEBUG o.e.jetty.io.SelectChannelEndPoint - Local interests updated 0 -> 1 for SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,Open,in,out,R,-,30000,HttpConnection}{io=1,kio=0,kro=1}
17:07:40.597 [qtp1626149608-44] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@6355b32
17:07:40.597 [qtp1626149608-44] DEBUG o.e.jetty.io.SelectChannelEndPoint - Key interests updated 0 -> 1
17:07:40.597 [qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/1 selected
17:07:40.597 [qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop waiting on select
17:07:40.611 [main] DEBUG c.c.c.l.r.c.LocalServerRestClient - execute: invocation took 3476 ms
Local repository:
  repository.repositoryId = 9a97329a-3110-47a7-8e10-b5cb6a534d65
  repository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/repo/j8rgh3bq-b0rd1
  repository.aliases = co.codewizards.cloudstore.ls.core.dto.RepoInfoResponseDto@61b60600
  repository.publicKeySha1 = BC:18:D7:43:79:0F:1D:A8:18:1A:A0:45:19:CC:75:E1:45:31:77:BF

Remote repositories connected: {NONE}

Remote repositories requesting connection: {NONE}

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

17:07:40.612 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.Server@24c8d8be
17:07:40.613 [main] DEBUG org.eclipse.jetty.server.Server - Graceful shutdown org.eclipse.jetty.server.Server@24c8d8be by 
17:07:40.613 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ServerConnector@5503de1{HTTP/1.1}{127.0.0.1:0}
17:07:40.613 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@343fddd9
17:07:40.613 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@67bb4dcd keys=1 selected=0
17:07:40.613 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@67bb4dcd keys=1 selected=0
17:07:40.613 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@27f71195
17:07:40.614 [qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/1 selected
17:07:40.614 [qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@27f71195
17:07:40.615 [qtp1626149608-49] DEBUG o.eclipse.jetty.io.AbstractEndPoint - onClose SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,CLOSED,in,out,R,-,30000,HttpConnection}{io=1,kio=1,kro=1}
17:07:40.616 [qtp1626149608-44] DEBUG o.e.jetty.io.AbstractConnection - FILL_INTERESTED-->IDLE HttpConnection@4337dd30{IDLE}
17:07:40.617 [qtp1626149608-44] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
17:07:40.617 [qtp1626149608-44] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSED
17:07:40.617 [qtp1626149608-44] DEBUG o.e.jetty.io.AbstractConnection - HttpConnection@4337dd30{IDLE} onFillInterestedFailed java.nio.channels.ClosedChannelException
17:07:40.617 [qtp1626149608-49] DEBUG org.eclipse.jetty.io.ChannelEndPoint - close SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,CLOSED,in,out,-,-,30000,HttpConnection}{io=1,kio=1,kro=1}
17:07:40.620 [qtp1626149608-49] DEBUG org.eclipse.jetty.io.SelectorManager - Destroyed SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,CLOSED,ISHUT,OSHUT,-,-,30000,HttpConnection}{io=1,kio=-1,kro=-1}
17:07:40.620 [qtp1626149608-49] DEBUG o.e.jetty.io.AbstractConnection - onClose HttpConnection@4337dd30{IDLE}
17:07:40.621 [qtp1626149608-49] DEBUG o.eclipse.jetty.io.AbstractEndPoint - onClose SelectChannelEndPoint@47085109{/127.0.0.1:34782<->41959,CLOSED,ISHUT,OSHUT,-,-,30000,HttpConnection}{io=1,kio=-1,kro=-1}
17:07:40.623 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@67bb4dcd keys=-1 selected=-1
17:07:40.623 [qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-37-selector-ServerConnectorManager@343fddd9/0,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@67bb4dcd keys=-1 selected=-1
17:07:40.623 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@67bb4dcd keys=-1 selected=-1
17:07:40.623 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@738d37fc keys=0 selected=0
17:07:40.624 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@738d37fc keys=0 selected=0
17:07:40.624 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@340afaf8
17:07:40.624 [qtp1626149608-39-selector-ServerConnectorManager@343fddd9/1] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/0 selected
17:07:40.624 [qtp1626149608-39-selector-ServerConnectorManager@343fddd9/1] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@340afaf8
17:07:40.626 [qtp1626149608-39-selector-ServerConnectorManager@343fddd9/1] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-39-selector-ServerConnectorManager@343fddd9/1,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@738d37fc keys=-1 selected=-1
17:07:40.626 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@738d37fc keys=-1 selected=-1
17:07:40.626 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@738d37fc keys=-1 selected=-1
17:07:40.626 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@6fa2448b keys=0 selected=0
17:07:40.627 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@6fa2448b keys=0 selected=0
17:07:40.627 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@6238d5e0
17:07:40.627 [qtp1626149608-38-selector-ServerConnectorManager@343fddd9/2] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/0 selected
17:07:40.628 [qtp1626149608-38-selector-ServerConnectorManager@343fddd9/2] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@6238d5e0
17:07:40.629 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@6fa2448b keys=-1 selected=-1
17:07:40.629 [qtp1626149608-38-selector-ServerConnectorManager@343fddd9/2] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-38-selector-ServerConnectorManager@343fddd9/2,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@6fa2448b keys=-1 selected=-1
17:07:40.629 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@6fa2448b keys=-1 selected=-1
17:07:40.629 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@61bb1e4d keys=0 selected=0
17:07:40.630 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@61bb1e4d keys=0 selected=0
17:07:40.630 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@336f49a1
17:07:40.630 [qtp1626149608-40-selector-ServerConnectorManager@343fddd9/3] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/0 selected
17:07:40.630 [qtp1626149608-40-selector-ServerConnectorManager@343fddd9/3] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@336f49a1
17:07:40.631 [qtp1626149608-40-selector-ServerConnectorManager@343fddd9/3] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-40-selector-ServerConnectorManager@343fddd9/3,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@61bb1e4d keys=-1 selected=-1
17:07:40.631 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@61bb1e4d keys=-1 selected=-1
17:07:40.631 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@61bb1e4d keys=-1 selected=-1
17:07:40.631 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@5c53f292 keys=0 selected=0
17:07:40.631 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@5c53f292 keys=0 selected=0
17:07:40.632 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@2c8b8de0
17:07:40.632 [qtp1626149608-41-selector-ServerConnectorManager@343fddd9/4] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/0 selected
17:07:40.633 [qtp1626149608-41-selector-ServerConnectorManager@343fddd9/4] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@2c8b8de0
17:07:40.633 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@5c53f292 keys=-1 selected=-1
17:07:40.634 [qtp1626149608-41-selector-ServerConnectorManager@343fddd9/4] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-41-selector-ServerConnectorManager@343fddd9/4,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@5c53f292 keys=-1 selected=-1
17:07:40.634 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@5c53f292 keys=-1 selected=-1
17:07:40.634 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@46fb0c33 keys=0 selected=0
17:07:40.635 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopping org.eclipse.jetty.io.SelectorManager$ManagedSelector@46fb0c33 keys=0 selected=0
17:07:40.635 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Queued change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@4fb57fb3
17:07:40.635 [qtp1626149608-42-selector-ServerConnectorManager@343fddd9/5] DEBUG org.eclipse.jetty.io.SelectorManager - Selector loop woken up from select, 0/0 selected
17:07:40.635 [qtp1626149608-42-selector-ServerConnectorManager@343fddd9/5] DEBUG org.eclipse.jetty.io.SelectorManager - Running change org.eclipse.jetty.io.SelectorManager$ManagedSelector$Stop@4fb57fb3
17:07:40.635 [qtp1626149608-42-selector-ServerConnectorManager@343fddd9/5] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped Thread[qtp1626149608-42-selector-ServerConnectorManager@343fddd9/5,5,main] on org.eclipse.jetty.io.SelectorManager$ManagedSelector@46fb0c33 keys=-1 selected=-1
17:07:40.635 [main] DEBUG org.eclipse.jetty.io.SelectorManager - Stopped org.eclipse.jetty.io.SelectorManager$ManagedSelector@46fb0c33 keys=-1 selected=-1
17:07:40.636 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.io.SelectorManager$ManagedSelector@46fb0c33 keys=-1 selected=-1
17:07:40.636 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.ServerConnector$ServerConnectorManager@343fddd9
17:07:40.636 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping HttpConnectionFactory@6fcd31c3{HTTP/1.1}
17:07:40.636 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED HttpConnectionFactory@6fcd31c3{HTTP/1.1}
17:07:40.636 [main] INFO  o.e.jetty.server.ServerConnector - Stopped ServerConnector@5503de1{HTTP/1.1}{127.0.0.1:0}
17:07:40.637 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ServerConnector@5503de1{HTTP/1.1}{127.0.0.1:0}
17:07:40.637 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.Server@24c8d8be
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.HashSessionIdManager@718f805a
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.HashSessionIdManager@718f805a
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping o.e.j.s.ServletContextHandler@4816ee24{/,null,SHUTDOWN}
17:07:40.638 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping o.e.j.s.ServletContextHandler@4816ee24{/,null,UNAVAILABLE}
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.SessionHandler@2bbb44da
17:07:40.638 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.session.SessionHandler@2bbb44da
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler@20d33ea
17:07:40.638 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping org.eclipse.jetty.servlet.ServletHandler@20d33ea
17:07:40.638 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-557b6a37@35cae04e==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,-1,false
17:07:40.639 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-557b6a37@35cae04e==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,-1,false
17:07:40.639 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.glassfish.jersey.servlet.ServletContainer-4ef2ab73@82897fc8==org.glassfish.jersey.servlet.ServletContainer,-1,true
17:07:40.639 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.glassfish.jersey.servlet.ServletContainer-4ef2ab73@82897fc8==org.glassfish.jersey.servlet.ServletContainer,-1,true
17:07:40.639 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler@20d33ea
17:07:40.640 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.HashSessionManager@2f80cb79
17:07:40.640 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.HashSessionManager@2f80cb79
17:07:40.640 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.SessionHandler@2bbb44da
17:07:40.640 [main] INFO  o.e.j.server.handler.ContextHandler - Stopped o.e.j.s.ServletContextHandler@4816ee24{/,null,UNAVAILABLE}
17:07:40.640 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED o.e.j.s.ServletContextHandler@4816ee24{/,null,UNAVAILABLE}
17:07:40.640 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@3290b1a6
17:07:40.641 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.util.thread.ScheduledExecutorScheduler@3290b1a6
17:07:40.641 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping qtp1626149608{STARTED,8<=9<=500,i=9,q=0}
17:07:40.649 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED qtp1626149608{STOPPED,8<=0<=500,i=0,q=0}
17:07:40.649 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.Server@24c8d8be
17:07:40.649 [main] INFO  c.c.cloudstore.ls.server.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/localServerRunning.deleteToStop
17:07:40.649 [main] DEBUG c.c.cloudstore.core.io.LockFileImpl - [2fd72332]release: releasing underlying FileLock. lockCounter=0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 26.515 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/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3.jar
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy-dependencies (copy-dependencies) @ co.codewizards.cloudstore.client ---
[INFO] Copying jetty-server-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-server-9.1.5.v20140505.jar
[INFO] Copying jersey-container-servlet-core-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/co.codewizards.cloudstore.client/target/dependency/transaction-api-1.1.jar
[INFO] Copying jetty-io-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-io-9.1.5.v20140505.jar
[INFO] Copying jetty-xml-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-xml-9.1.5.v20140505.jar
[INFO] Copying cglib-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/co.codewizards.cloudstore.client/target/dependency/jdo-api-3.1-rc1.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.client-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.client-0.10.3.jar
[INFO] Copying co.codewizards.cloudstore.rest.client-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.client-0.10.3.jar
[INFO] Copying jersey-server-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jersey-server-2.5.1.jar
[INFO] Copying args4j-2.0.16.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/args4j-2.0.16.jar
[INFO] Copying jetty-webapp-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-webapp-9.1.5.v20140505.jar
[INFO] Copying jersey-container-servlet-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jersey-container-servlet-2.5.1.jar
[INFO] Copying jetty-security-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-security-9.1.5.v20140505.jar
[INFO] Copying co.codewizards.cloudstore.local-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.local-0.10.3.jar
[INFO] Copying co.codewizards.cloudstore.rest.shared-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.shared-0.10.3.jar
[INFO] Copying hk2-api-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/hk2-api-2.2.0-b21.jar
[INFO] Copying javax.inject-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/javax.inject-2.2.0-b21.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.server-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.server-0.10.3.jar
[INFO] Copying validation-api-1.1.0.Final.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/validation-api-1.1.0.Final.jar
[INFO] Copying jetty-servlet-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-servlet-9.1.5.v20140505.jar
[INFO] Copying log4j-over-slf4j-1.7.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/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/co.codewizards.cloudstore.client/target/dependency/asm-all-repackaged-2.2.0-b21.jar
[INFO] Copying commons-compress-1.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/commons-compress-1.7.jar
[INFO] Copying hk2-utils-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/hk2-utils-2.2.0-b21.jar
[INFO] Copying hk2-locator-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/hk2-locator-2.2.0-b21.jar
[INFO] Copying jetty-util-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-util-9.1.5.v20140505.jar
[INFO] Copying datanucleus-core-4.0.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-core-4.0.3.jar
[INFO] Copying javax.ws.rs-api-2.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/javax.ws.rs-api-2.0.jar
[INFO] Copying co.codewizards.cloudstore.core.oio.nio-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core.oio.nio-0.10.3.jar
[INFO] Copying co.codewizards.cloudstore.core-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core-0.10.3.jar
[INFO] Copying guava-14.0.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/guava-14.0.1.jar
[INFO] Copying bcprov-jdk15on-1.52.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/bcprov-jdk15on-1.52.jar
[INFO] Copying javax.annotation-api-1.2.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/javax.annotation-api-1.2.jar
[INFO] Copying datanucleus-rdbms-4.0.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-rdbms-4.0.3.jar
[INFO] Copying jersey-common-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jersey-common-2.5.1.jar
[INFO] Copying osgi-resource-locator-1.0.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/co.codewizards.cloudstore.client/target/dependency/javax.servlet-api-3.1.0.jar
[INFO] Copying co.codewizards.cloudstore.ls.core-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.core-0.10.3.jar
[INFO] Copying logback-core-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/logback-core-1.0.13.jar
[INFO] Copying co.codewizards.cloudstore.ls.server.cproc-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server.cproc-0.10.3.jar
[INFO] Copying bcpg-jdk15on-1.52.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/bcpg-jdk15on-1.52.jar
[INFO] Copying logback-classic-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/co.codewizards.cloudstore.client/target/dependency/jersey-client-2.5.1.jar
[INFO] Copying co.codewizards.cloudstore.updater-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.updater-0.10.3.jar
[INFO] Copying jetty-servlets-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-servlets-9.1.5.v20140505.jar
[INFO] Copying jetty-continuation-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-continuation-9.1.5.v20140505.jar
[INFO] Copying co.codewizards.cloudstore.ls.server-0.10.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server-0.10.3.jar
[INFO] Copying xz-1.4.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/xz-1.4.jar
[INFO] Copying jetty-http-9.1.5.v20140505.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-http-9.1.5.v20140505.jar
[INFO] Copying datanucleus-api-jdo-4.0.3.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-api-jdo-4.0.3.jar
[INFO] Copying slf4j-api-1.7.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/slf4j-api-1.7.7.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/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-bin.tar.gz
[INFO] Building zip: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-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/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-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.3.1:install (default-install) @ co.codewizards.cloudstore.client ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-bin.tar.gz to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.tar.gz
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-bin.zip to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.zip
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.3-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.client ---
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.jar (44 KB at 7256.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3.pom (4 KB at 3693.4 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (686 B at 55.8 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (718 B at 175.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.tar.gz
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.tar.gz (17253 KB at 51653.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.zip
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-bin.zip (19189 KB at 52859.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.3/co.codewizards.cloudstore.client-0.10.3-sources.jar (26 KB at 12958.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/co.codewizards.cloudstore.client/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore/site/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) @ 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/co.codewizards.cloudstore.client/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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/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/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