Console Output

dewizards.cloudstore.local.persistence.Symlink" : Populating Meta-Data
15:28:22.083 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /META-INF/package.orm
15:28:22.084 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /WEB-INF/package.orm
15:28:22.085 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /package.orm
15:28:22.086 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/package.orm
15:28:22.087 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co.orm
15:28:22.088 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/package.orm
15:28:22.089 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards.orm
15:28:22.090 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/package.orm
15:28:22.092 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore.orm
15:28:22.092 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/package.orm
15:28:22.093 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local.orm
15:28:22.094 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/package.orm
15:28:22.094 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence.orm
15:28:22.095 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" NOT found at /co/codewizards/cloudstore/local/persistence/Symlink.orm
15:28:22.095 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.Symlink" not found
15:28:22.095 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.Symlink" : Initialising Meta-Data
15:28:22.096 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.Symlink [Table : "repofile", InheritanceStrategy : superclass-table]
15:28:22.096 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Table "repofile" will manage the persistence of the fields for class co.codewizards.cloudstore.local.persistence.Symlink (inheritance strategy="superclass-table") 
15:28:22.096 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""repofile"."target"" added to internal representation of table.
15:28:22.097 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Member co.codewizards.cloudstore.local.persistence.Symlink.target uses superclass-table yet the field is not marked as nullable  nor does it have a default value, so setting the column as nullable
15:28:22.097 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Field [co.codewizards.cloudstore.local.persistence.Symlink.target] -> Column(s) ["repofile"."target"] using mapping of type "org.datanucleus.store.rdbms.mapping.java.StringMapping" (org.datanucleus.store.rdbms.mapping.datastore.VarCharRDBMSMapping)
15:28:22.097 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5ff96cc4 [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
15:28:22.097 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@73d84738" with isolation "serializable"
15:28:22.102 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "repofile" returned table type of TABLE
15:28:22.110 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 6 index(es) for table "repofile"
15:28:22.123 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 foreign key(s) for table "repofile"
15:28:22.126 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 2 unique key(s) for table "repofile"
15:28:22.126 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@73d84738"
15:28:22.126 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@73d84738"
15:28:22.126 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5ff96cc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@73d84738, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
15:28:22.126 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5ff96cc4 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@73d84738, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
15:28:22.127 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /META-INF/package.jdo
15:28:22.128 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /WEB-INF/package.jdo
15:28:22.129 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /package.jdo
15:28:22.129 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/package.jdo
15:28:22.130 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co.jdo
15:28:22.130 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/package.jdo
15:28:22.131 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards.jdo
15:28:22.132 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/package.jdo
15:28:22.133 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore.jdo
15:28:22.134 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/package.jdo
15:28:22.134 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local.jdo
15:28:22.135 [qtp1002031672-38] 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
15:28:22.136 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence.jdo
15:28:22.136 [qtp1002031672-38] 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
15:28:22.136 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" not found
15:28:22.137 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" has been specified with JDO annotations so using those.
15:28:22.138 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" : Populating Meta-Data
15:28:22.138 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /META-INF/package.orm
15:28:22.139 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /WEB-INF/package.orm
15:28:22.140 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /package.orm
15:28:22.140 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/package.orm
15:28:22.141 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co.orm
15:28:22.141 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/package.orm
15:28:22.142 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards.orm
15:28:22.143 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/package.orm
15:28:22.143 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore.orm
15:28:22.144 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/package.orm
15:28:22.144 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local.orm
15:28:22.145 [qtp1002031672-38] 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
15:28:22.146 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" NOT found at /co/codewizards/cloudstore/local/persistence.orm
15:28:22.146 [qtp1002031672-38] 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
15:28:22.146 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "orm" for class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" not found
15:28:22.146 [qtp1002031672-38] 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.
15:28:22.147 [qtp1002031672-38] 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.
15:28:22.147 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Java type co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType using the support for java.lang.Enum
15:28:22.148 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /META-INF/package.jdo
15:28:22.148 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /WEB-INF/package.jdo
15:28:22.149 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /package.jdo
15:28:22.149 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/package.jdo
15:28:22.150 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co.jdo
15:28:22.150 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/package.jdo
15:28:22.151 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards.jdo
15:28:22.152 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/package.jdo
15:28:22.152 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore.jdo
15:28:22.153 [qtp1002031672-38] 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
15:28:22.154 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" NOT found at /co/codewizards/cloudstore/core.jdo
15:28:22.154 [qtp1002031672-38] 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
15:28:22.155 [qtp1002031672-38] 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
15:28:22.156 [qtp1002031672-38] 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
15:28:22.156 [qtp1002031672-38] 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
15:28:22.157 [qtp1002031672-38] 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
15:28:22.157 [qtp1002031672-38] DEBUG DataNucleus.MetaData - MetaData of type "jdo" for class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" not found
15:28:22.157 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Registering class "co.codewizards.cloudstore.core.repo.transport.TransferDoneMarkerType" as not having MetaData.
15:28:22.157 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Class "co.codewizards.cloudstore.local.persistence.TransferDoneMarker" : Initialising Meta-Data
15:28:22.158 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Managing Persistence of Class : co.codewizards.cloudstore.local.persistence.TransferDoneMarker [Table : "transferdonemarker", InheritanceStrategy : new-table]
15:28:22.158 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."id"" added to internal representation of table.
15:28:22.158 [qtp1002031672-38] 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)
15:28:22.159 [qtp1002031672-38] 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") 
15:28:22.159 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromentityid"" added to internal representation of table.
15:28:22.159 [qtp1002031672-38] 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)
15:28:22.159 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromlocalrevision"" added to internal representation of table.
15:28:22.159 [qtp1002031672-38] 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)
15:28:22.160 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."fromrepositoryid"" added to internal representation of table.
15:28:22.160 [qtp1002031672-38] 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)
15:28:22.160 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."torepositoryid"" added to internal representation of table.
15:28:22.160 [qtp1002031672-38] 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)
15:28:22.160 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."transferdonemarkertype"" added to internal representation of table.
15:28:22.161 [qtp1002031672-38] 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)
15:28:22.161 [qtp1002031672-38] 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") 
15:28:22.161 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."changed"" added to internal representation of table.
15:28:22.161 [qtp1002031672-38] 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)
15:28:22.161 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column ""transferdonemarker"."created"" added to internal representation of table.
15:28:22.161 [qtp1002031672-38] 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)
15:28:22.161 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Table/View "transferdonemarker" has been initialised
15:28:22.162 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4afa41f9 [conn=null, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" opened with isolation level "serializable" and auto-commit=false
15:28:22.162 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction started with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3b3cb69" with isolation "serializable"
15:28:22.163 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Check of existence of "transferdonemarker" returned table type of TABLE
15:28:22.163 [qtp1002031672-38] 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 ""
15:28:22.187 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column info loaded for Catalog "", Schema "", 11 tables, time = 24 ms
15:28:22.187 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Column info retrieved for table "transferdonemarker" : 8 columns found
15:28:22.189 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 index(es) for table "transferdonemarker"
15:28:22.189 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Creating index "TransferDoneMarker_fromRepositoryId_toRepositoryId" in catalog "" schema ""
15:28:22.189 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - CREATE INDEX "transferdonemarker_fromrepositoryid_torepositoryid" ON "transferdonemarker" ("fromrepositoryid","torepositoryid")
15:28:22.210 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Execution Time = 21 ms
15:28:22.238 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 0 foreign key(s) for table "transferdonemarker"
15:28:22.257 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Validating 1 unique key(s) for table "transferdonemarker"
15:28:22.258 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Creating candidate key "TransferDoneMarker_fromRepositoryId_toRepositoryId_transferDoneMarkerType_fromEntityId" in catalog "" schema ""
15:28:22.258 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - CREATE UNIQUE INDEX "transferdonemarker_fromrepositoryid_torepositoryid_transferdonemarkertype_fromentityid" ON "transferdonemarker" ("fromrepositoryid","torepositoryid","transferdonemarkertype","fromentityid")
15:28:22.284 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Execution Time = 26 ms
15:28:22.284 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction committing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3b3cb69"
15:28:22.290 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Schema - Schema Transaction closing with connection "org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3b3cb69"
15:28:22.290 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4afa41f9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3b3cb69, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" is being committed.
15:28:22.290 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@4afa41f9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3b3cb69, commitOnRelease=true, closeOnRelease=true, closeOnTxnEnd=true]" closed
15:28:22.291 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.291 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.291 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@21e91e9c" with txn="org.datanucleus.TransactionImpl@277fcb0f"
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420 (optimistic=false)
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository"
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.LocalRepository]
15:28:22.292 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" for datastore
15:28:22.294 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
15:28:22.294 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository Query compiled to datastore query "SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'"
15:28:22.296 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
15:28:22.296 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@57133271, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.296 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
15:28:22.297 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.297 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
15:28:22.306 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@777af809"
15:28:22.306 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
15:28:22.307 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.308 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 11 ms
15:28:22.308 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
15:28:22.308 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 2 cache
15:28:22.309 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
15:28:22.310 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="1") added to Level 2 cache (fields="[0, 1, 2, 4, 5]", version="")
15:28:22.310 [qtp1002031672-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "HOLLOW"->"P_CLEAN"
15:28:22.310 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="1") enlisted in transactional cache
15:28:22.312 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" having fields "publicKey" fetched from table ""repository""
15:28:22.312 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.320 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@642e4988"
15:28:22.320 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."publickey" FROM "repository" "a0" WHERE "a0"."id" = <1>
15:28:22.321 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.321 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@4f544666"
15:28:22.321 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="1") is having the following fields in Level 2 cache object updated : [3]
15:28:22.322 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" having fields "privateKey" fetched from table ""repository""
15:28:22.323 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.329 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@42959981"
15:28:22.329 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."privatekey" FROM "repository" "a0" WHERE "a0"."id" = <1>
15:28:22.330 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.330 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@7fb070df"
15:28:22.330 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="1") is having the following fields in Level 2 cache object updated : [7]
15:28:22.330 [qtp1002031672-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4b6404a2]initPersistenceManagerFactory: repositoryId=e53e7d62-ce73-4e52-87ec-ab540e1d7077
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@57133271]]
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
15:28:22.331 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@5cce79bc [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@71171d06, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@50132ac6, lifecycle=HOLLOW]
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.332 [qtp1002031672-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4b6404a2]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2587 ms.
15:28:22.332 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@21e91e9c" with txn="org.datanucleus.TransactionImpl@68bb2b2b"
15:28:22.333 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.333 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420 (optimistic=false)
15:28:22.333 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Single-String with "SELECT WHERE this.changed < :changed"
15:28:22.333 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed"
15:28:22.334 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
15:28:22.334 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [filter:DyadicExpression{PrimaryExpression{this.changed}  <  ParameterExpression{changed}}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest, changed type=java.util.Date]
15:28:22.334 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" for datastore
15:28:22.336 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
15:28:22.336 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed Query compiled to datastore query "SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0" WHERE "a0"."changed" < ?"
15:28:22.337 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
15:28:22.337 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@23b1f9c4, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.337 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7b6d2a36, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
15:28:22.337 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7b6d2a36, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.337 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest WHERE this.changed < :changed" ...
15:28:22.345 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@4f89108a"
15:28:22.346 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0" WHERE "a0"."changed" < <2019-07-26 15:28:22.333>
15:28:22.346 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.346 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
15:28:22.347 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420
15:28:22.347 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.347 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.347 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@23b1f9c4]]
15:28:22.347 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7b6d2a36, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
15:28:22.348 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7b6d2a36, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
15:28:22.348 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@12626ad0 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7b6d2a36, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.348 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
15:28:22.348 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.348 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.348 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [3b639c92]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
15:28:22.349 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [3b639c92]tryAcquire: returning true. lockCounter=1
15:28:22.349 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [3b639c92]tryAcquire: returning true. lockCounter=2
15:28:22.349 [qtp1002031672-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
15:28:22.349 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [3b639c92]release: NOT releasing underlying FileLock. lockCounter=1
15:28:22.349 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [3b639c92]release: releasing underlying FileLock. lockCounter=0
15:28:22.350 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@21e91e9c" with txn="org.datanucleus.TransactionImpl@20bc2927"
15:28:22.350 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
15:28:22.350 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420 (optimistic=false)
15:28:22.350 [qtp1002031672-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" has been run before so reusing existing generic compilation
15:28:22.350 [qtp1002031672-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" for datastore "rdbms-derby" has been run before so reusing existing datastore compilation
15:28:22.351 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
15:28:22.351 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@63a29302, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=
, enlisted resources=[]]
15:28:22.351 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=
" with flags "0"
15:28:22.351 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.352 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
15:28:22.352 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@6eeb343d"
15:28:22.352 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
15:28:22.352 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.353 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
15:28:22.353 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
15:28:22.353 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
15:28:22.353 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
15:28:22.354 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
15:28:22.354 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="1") enlisted in transactional cache
15:28:22.354 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" taken from Level 2 cache (fields="[0, 1, 2, 3, 4, 5, 7]", version="") - represented as "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e"
15:28:22.355 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, allow-nulls=false]
15:28:22.355 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="1") is having the following fields in Level 2 cache object updated : [6]
15:28:22.356 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" field "aliases" loading contents to SCO wrapper from the datastore
15:28:22.356 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.366 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@2b6cf91c"
15:28:22.367 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
15:28:22.368 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.368 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@2bbc57e3"
15:28:22.368 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6350a527]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
15:28:22.368 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6350a527]tryAcquire: returning true. lockCounter=1
15:28:22.369 [qtp1002031672-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
15:28:22.369 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [6350a527]release: releasing underlying FileLock. lockCounter=0
15:28:22.369 [qtp1002031672-38] DEBUG c.c.c.local.LocalRepoManagerImpl - syncWithLocalRepoRegistry: Alias 'jylkkxua-1gn43' of repository 'e53e7d62-ce73-4e52-87ec-ab540e1d7077' already in-sync.
15:28:22.370 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [36c3177d]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
15:28:22.370 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [36c3177d]tryAcquire: returning true. lockCounter=1
15:28:22.370 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [36c3177d]tryAcquire: returning true. lockCounter=2
15:28:22.370 [qtp1002031672-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
15:28:22.370 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [36c3177d]release: NOT releasing underlying FileLock. lockCounter=1
15:28:22.370 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [36c3177d]release: releasing underlying FileLock. lockCounter=0
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.371 [qtp1002031672-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.371 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=
, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@63a29302]]
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=
" with onePhase="true"
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2b7443a9 [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@8f25f35, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@92f1c0e, lifecycle=HOLLOW]
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
15:28:22.372 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.373 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.373 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@21e91e9c" with txn="org.datanucleus.TransactionImpl@76e37b7c"
15:28:22.373 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.373 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420 (optimistic=false)
15:28:22.373 [qtp1002031672-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" has been run before so reusing existing generic compilation
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Query - Query "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository FetchPlan [default]" of language "JDOQL" for datastore "rdbms-derby" has been run before so reusing existing datastore compilation
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5b6f6a6d, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.LocalRepository" ...
15:28:22.374 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@7a4dd5fa"
15:28:22.375 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'LocalRepository'
15:28:22.375 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.375 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 1 ms
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id "co.codewizards.cloudstore.local.persistence.LocalRepository:1" not found in Level 1 cache [cache size = 0]
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") added to Level 1 cache (loadedFlags="[NNYNNNNNN]")
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "changed" replaced by a SCO wrapper
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") is having the value in field "created" replaced by a SCO wrapper
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="1") enlisted in transactional cache
15:28:22.376 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" taken from Level 2 cache (fields="[0, 1, 2, 3, 4, 5, 7]", version="") - represented as "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82"
15:28:22.377 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" field "aliases" is replaced by a SCO wrapper of type "org.datanucleus.store.types.wrappers.backed.Set" [cache-values=true, lazy-loading=true, allow-nulls=false]
15:28:22.377 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="1") is having the following fields in Level 2 cache object updated : [6]
15:28:22.377 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.386 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@34359da6"
15:28:22.386 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT(*) FROM "localrepository_aliases" THIS WHERE THIS."id_oid"=<1>
15:28:22.387 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.388 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@5ebbbda1"
15:28:22.388 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" field "aliases" loading contents to SCO wrapper from the datastore
15:28:22.389 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.389 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@12b263b"
15:28:22.389 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."element" FROM "localrepository_aliases" "a0" WHERE "a0"."id_oid" = <1>
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.ParamLoggingPreparedStatement@3470f6a3"
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.390 [qtp1002031672-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.390 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420
15:28:22.391 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.391 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.391 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@5b6f6a6d]]
15:28:22.391 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@2cbc293a [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@3a86a4e5, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Lifecycle - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") has a lifecycle change : "P_CLEAN"->"HOLLOW"
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Object "co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82" (id="co.codewizards.cloudstore.local.persistence.LocalRepository:1") was evicted from transactional cache
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committed in 2 ms
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Disconnecting co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82 from StateManager[pc=co.codewizards.cloudstore.local.persistence.LocalRepository@589b5a82, lifecycle=HOLLOW]
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Cache - Object with id="co.codewizards.cloudstore.local.persistence.LocalRepository:1" being removed from Level 1 cache [current cache size = 1]
15:28:22.392 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.393 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.393 [qtp1002031672-38] DEBUG c.c.c.local.LocalRepoManagerImpl - [4b6404a2]open: closing=false closeAbortable=true
15:28:22.393 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" opened for datastore "org.datanucleus.store.rdbms.RDBMSStoreManager@21e91e9c" with txn="org.datanucleus.TransactionImpl@6f67f118"
15:28:22.393 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction created [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.393 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction begun for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420 (optimistic=false)
15:28:22.393 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [822edb]<init>: file='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/.cloudstore/repoRegistry.properties'
15:28:22.394 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [822edb]tryAcquire: returning true. lockCounter=1
15:28:22.394 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [822edb]tryAcquire: returning true. lockCounter=2
15:28:22.394 [qtp1002031672-38] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='cloudstore.repoRegistry.evictDeadEntriesPeriod' is not set (config is queried next).
15:28:22.394 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [822edb]release: NOT releasing underlying FileLock. lockCounter=1
15:28:22.395 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [822edb]release: releasing underlying FileLock. lockCounter=0
15:28:22.395 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository"
15:28:22.396 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
15:28:22.396 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepository]
15:28:22.396 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" for datastore
15:28:22.397 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository Query compiled to datastore query "SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."localpathprefix","a0"."localrevision","a0"."remoteroot","a0"."remoterootsha1","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'RemoteRepository'"
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=null, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" opened with isolation level "read-committed" and auto-commit=false
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Running enlist operation on resource: org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@435afcb5, error code TMNOFLAGS and transaction: [DataNucleus Transaction, ID=Xid=, enlisted resources=[]]
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" starting for transaction "Xid=" with flags "0"
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection added to the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.398 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepository" ...
15:28:22.405 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@65089700"
15:28:22.406 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."localpathprefix","a0"."localrevision","a0"."remoteroot","a0"."remoterootsha1","a0"."discriminator" FROM "repository" "a0" WHERE "a0"."discriminator" = 'RemoteRepository'
15:28:22.406 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.407 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
15:28:22.407 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest"
15:28:22.408 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
15:28:22.408 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [symbols: this type=co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest]
15:28:22.408 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" for datastore
15:28:22.409 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
15:28:22.409 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest Query compiled to datastore query "SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0""
15:28:22.409 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.409 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT FROM co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest" ...
15:28:22.414 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@1737a583"
15:28:22.415 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT 'co.codewizards.cloudstore.local.persistence.RemoteRepositoryRequest' AS NUCLEUS_TYPE,"a0"."changed","a0"."created","a0"."id","a0"."localpathprefix","a0"."repositoryid" FROM "remoterepositoryrequest" "a0"
15:28:22.415 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.415 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 6 ms
15:28:22.416 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile"
15:28:22.417 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 1 ms
15:28:22.417 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.NormalFile]
15:28:22.417 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" for datastore
15:28:22.419 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 2 ms
15:28:22.419 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'"
15:28:22.419 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.419 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.NormalFile" ...
15:28:22.429 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@35a5d6b5"
15:28:22.429 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'NormalFile'
15:28:22.430 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 1 ms
15:28:22.430 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 11 ms
15:28:22.431 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory"
15:28:22.431 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
15:28:22.431 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.Directory]
15:28:22.432 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" for datastore
15:28:22.433 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
15:28:22.433 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'"
15:28:22.433 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.433 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.Directory" ...
15:28:22.440 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@3f20c4a6"
15:28:22.441 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "repofile" "a0" WHERE "a0"."discriminator" = 'Directory'
15:28:22.441 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.442 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
15:28:22.443 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification"
15:28:22.443 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
15:28:22.444 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.CopyModification]
15:28:22.444 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" for datastore
15:28:22.445 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
15:28:22.445 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "copymodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'CopyModification'"
15:28:22.445 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.445 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.CopyModification" ...
15:28:22.456 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@1257ee38"
15:28:22.457 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "copymodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'CopyModification'
15:28:22.457 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.457 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 12 ms
15:28:22.458 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification"
15:28:22.458 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time = 0 ms
15:28:22.459 [qtp1002031672-38] DEBUG DataNucleus.Query - QueryCompilation:
  [result:InvokeExpression{STATIC.count(PrimaryExpression{this})}]
  [symbols: this type=co.codewizards.cloudstore.local.persistence.DeleteModification]
15:28:22.459 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compiling "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" for datastore
15:28:22.460 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Compile Time for datastore = 1 ms
15:28:22.460 [qtp1002031672-38] DEBUG DataNucleus.Query - SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification Query compiled to datastore query "SELECT COUNT("a0"."id") FROM "deletemodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'DeleteModification'"
15:28:22.461 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection found in the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.461 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Executing "SELECT count(this) FROM co.codewizards.cloudstore.local.persistence.DeleteModification" ...
15:28:22.469 [qtp1002031672-38] DEBUG DataNucleus.Datastore - Closing PreparedStatement "org.datanucleus.store.rdbms.datasource.dbcp.DelegatingPreparedStatement@53138f97"
15:28:22.470 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Native - SELECT COUNT("a0"."id") FROM "deletemodification" "a0" INNER JOIN "modification" "a1" ON "a0"."id" = "a1"."id" WHERE "a1"."discriminator" = 'DeleteModification'
15:28:22.470 [qtp1002031672-38] DEBUG DataNucleus.Datastore.Retrieve - Execution Time = 0 ms
15:28:22.470 [qtp1002031672-38] DEBUG DataNucleus.Query - JDOQL Query : Execution Time = 9 ms
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.471 [qtp1002031672-38] DEBUG c.c.c.l.AutoTrackLifecycleListener - onCommit: Deferred operations took 0 ms for 0 entities.
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committing for ExecutionContext org.datanucleus.ExecutionContextImpl@10f6f420
15:28:22.471 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process started using ordered flush - 0 enlisted objects
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext.internalFlush() process finished
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Committing [DataNucleus Transaction, ID=Xid=, enlisted resources=[org.datanucleus.store.rdbms.ConnectionFactoryImpl$EmulatedXAResource@435afcb5]]
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" committing for transaction "Xid=" with onePhase="true"
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection(non-enlisted) "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" closed
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Connection - ManagedConnection removed from the pool : "org.datanucleus.store.rdbms.ConnectionFactoryImpl$ManagedConnectionImpl@523fe4df [conn=org.datanucleus.store.rdbms.datasource.dbcp.PoolingDataSource$PoolGuardConnectionWrapper@7c5f3ecc, commitOnRelease=false, closeOnRelease=false, closeOnTxnEnd=true]" for key="org.datanucleus.ExecutionContextImpl@10f6f420" in factory="ConnectionFactory:tx[org.datanucleus.store.rdbms.ConnectionFactoryImpl@692023f7]"
15:28:22.472 [qtp1002031672-38] DEBUG DataNucleus.Transaction - Transaction committed in 1 ms
15:28:22.473 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 1 Cache cleared
15:28:22.473 [qtp1002031672-38] DEBUG DataNucleus.Persistence - ExecutionContext "org.datanucleus.ExecutionContextImpl@10f6f420" closed
15:28:22.473 [qtp1002031672-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4b6404a2]getCloseDeferredMillis: closeDeferredMillis=0
15:28:22.473 [qtp1002031672-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4b6404a2]close: Closing real LocalRepoManager immediately.
15:28:22.473 [qtp1002031672-38] INFO  c.c.c.local.LocalRepoManagerImpl - [4b6404a2]_close: Shutting down real LocalRepoManager.
15:28:22.694 [qtp1002031672-38] DEBUG DataNucleus.Connection - Closing Connection Pool tx
15:28:22.696 [qtp1002031672-38] DEBUG DataNucleus.Connection - Closing Connection Pool nontx
15:28:22.696 [qtp1002031672-38] DEBUG DataNucleus.Persistence - Unmanaging Persistence of all classes
15:28:22.696 [qtp1002031672-38] DEBUG DataNucleus.MetaData - Deregistering listener for metadata initialisation
15:28:22.699 [qtp1002031672-38] DEBUG DataNucleus.Cache - Level 2 Cache cleared
15:28:23.122 [qtp1002031672-38] DEBUG c.c.cloudstore.core.io.LockFileImpl - [23b14822]release: releasing underlying FileLock. lockCounter=0
15:28:23.127 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@3025ab80[p=0,l=765,c=8192,r=765]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} complete=true committing=true callback=Blocker@28503a75{null}
15:28:23.128 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /RepoInfo on HttpChannelOverHttp@b0b9c47{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:41823/RepoInfo,age=3438}
200 OK HTTP/1.1
Content-Length: 765
Content-Type: application/xml


15:28:23.128 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@51539435[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=765},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@2623ed5e] generate: NEED_HEADER (null,[p=0,l=765,c=8192,r=765],true)@START
15:28:23.129 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=200,h=2,cl=765} last=true content=HeapByteBuffer@3025ab80[p=0,l=765,c=8192,r=765]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
15:28:23.130 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpGenerator - Content-Length: 765
Content-Type: application/xml


15:28:23.130 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
15:28:23.130 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@51539435[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=765},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@2623ed5e] generate: FLUSH ([p=0,l=104,c=8192,r=104],[p=0,l=765,c=8192,r=765],true)@COMPLETING
15:28:23.131 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@3bc72bb1{IDLE}->null [HeapByteBuffer@29e62cae[p=0,l=104,c=8192,r=104]={<<<HTTP/1.1 200 OK\r\n....v20190418)\r\n\r\n>>>hYS0dUemxLbVdNN3I...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@3025ab80[p=0,l=765,c=8192,r=765]={<<<<?xml version="1....nfoResponseDto>>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}]
15:28:23.132 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3bc72bb1{WRITING}->null:IDLE-->WRITING
15:28:23.134 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 869 SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=W,to=3444/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=END,236 of 236},g=HttpGenerator@2c8845a6{s=COMPLETING}]=>HttpChannelOverHttp@b0b9c47{r=1,c=true,c=false/false,a=DISPATCHED,uri=//127.0.0.1:41823/RepoInfo,age=3444}
15:28:23.135 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=869 remaining=0 WriteFlusher@3bc72bb1{WRITING}->null
15:28:23.136 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@3bc72bb1{IDLE}->null:WRITING-->IDLE
15:28:23.136 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@51539435[PROCESSING][i=HTTP/1.1{s=200,h=2,cl=765},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@2623ed5e] generate: DONE ([p=104,l=104,c=8192,r=0],[p=765,l=765,c=8192,r=0],true)@END
15:28:23.141 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.session - FinalSession=null, old_session_handler=null, this=org.eclipse.jetty.server.session.SessionHandler59295001==dftMaxIdleSec=-1, calling complete=true
15:28:23.141 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.session - Complete called with session null
15:28:23.141 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@b0b9c47{r=1,c=true,c=false/true,a=DISPATCHED,uri=//127.0.0.1:41823/RepoInfo,age=3451}
15:28:23.141 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpChannelState - unhandle HttpChannelState@f3409fe{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
15:28:23.141 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@b0b9c47{r=1,c=true,c=false/true,a=COMPLETING,uri=//127.0.0.1:41823/RepoInfo,age=3451} action COMPLETE
15:28:23.141 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpChannelState - onComplete HttpChannelState@f3409fe{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
15:28:23.142 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /RepoInfo written=765
15:28:23.142 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpChannelState - recycle HttpChannelState@f3409fe{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
15:28:23.142 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,236 of 236}
15:28:23.142 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
15:28:23.142 [qtp1002031672-38] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} handle exit, result COMPLETE
15:28:23.142 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@29e62cae[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
15:28:23.143 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 HeapByteBuffer@29e62cae[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
15:28:23.143 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=-,to=9/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} filled 0 HeapByteBuffer@29e62cae[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
15:28:23.144 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@29e62cae[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
15:28:23.144 [qtp1002031672-38] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@29e62cae[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 200 OK\r\n...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
15:28:23.145 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=-,to=10/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
15:28:23.145 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.145 [qtp1002031672-38] DEBUG o.e.jetty.io.AbstractConnection - fillInterested HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=-,flush=-,to=11/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.146 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@7bb61dd4{AC.ReadCB@4a36967c{HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
15:28:23.146 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.146 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@54e290a5 on ManagedSelector@dbddbe3{STARTED} id=1 keys=1 selected=0 updates=0
15:28:23.147 [qtp1002031672-38] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@dbddbe3{STARTED} id=1 keys=1 selected=0 updates=1
15:28:23.147 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken with none selected
15:28:23.147 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken up from select, 0/0/1 selected
15:28:23.147 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 processing 0 keys, 1 updates
15:28:23.147 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.147 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ChannelEndPoint$1@54e290a5
15:28:23.147 [qtp1002031672-38] DEBUG o.e.jetty.server.HttpConnection - HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@f3409fe{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
15:28:23.148 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.148 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.148 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 waiting with 1 keys
15:28:23.148 [qtp1002031672-38] DEBUG o.e.j.util.thread.QueuedThreadPool - ran CEP:SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
15:28:23.152 [main] DEBUG c.c.c.l.r.c.LocalServerRestClient - execute: invocation took 3517 ms
Local repository:
  repository.repositoryId = e53e7d62-ce73-4e52-87ec-ab540e1d7077
  repository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/repo/jylkkxua-1gn43
  repository.aliases = co.codewizards.cloudstore.ls.core.dto.RepoInfoResponseDto@2d4fb0d8
  repository.publicKeySha1 = 5F:88:F3:C9:95:E0:BB:60:C5:C5:7C:A0:5D:B6:B5:C0:A3:2F:D5:EA

Remote repositories connected: {NONE}

Remote repositories requesting connection: {NONE}

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

15:28:23.153 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping Server@160e99e0{STARTED}[9.4.17.v20190418]
15:28:23.154 [main] DEBUG org.eclipse.jetty.server.Server - doStop Server@160e99e0{STOPPING}[9.4.17.v20190418]
15:28:23.154 [main] DEBUG o.e.j.s.h.AbstractHandlerContainer - Graceful shutdown Server@160e99e0{STOPPING}[9.4.17.v20190418] by 
15:28:23.154 [qtp1002031672-35] DEBUG o.e.j.util.thread.QueuedThreadPool - ran qtp1002031672-35-acceptor-0@4bf03fee-ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:41823}
15:28:23.154 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
15:28:23.155 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping SelectorManager@ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
15:28:23.155 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@599a9cb2{STARTED} id=2 keys=0 selected=0 updates=0
15:28:23.155 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@dc1fadd on ManagedSelector@599a9cb2{STOPPING} id=2 keys=0 selected=0 updates=0
15:28:23.155 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@599a9cb2{STOPPING} id=2 keys=0 selected=0 updates=1
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b woken with none selected
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b woken up from select, 0/0/0 selected
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b processing 0 keys, 1 updates
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@dc1fadd
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@599a9cb2{STOPPING} id=2 keys=0 selected=0 updates=0
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.156 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b waiting with 0 keys
15:28:23.156 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@3ef3f661 on ManagedSelector@599a9cb2{STOPPING} id=2 keys=0 selected=0 updates=0
15:28:23.157 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@599a9cb2{STOPPING} id=2 keys=0 selected=0 updates=1
15:28:23.157 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b woken with none selected
15:28:23.157 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b woken up from select, 0/0/0 selected
15:28:23.157 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@1a021f3b processing 0 keys, 1 updates
15:28:23.157 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.157 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@3ef3f661
15:28:23.158 [qtp1002031672-34] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.158 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@7a83ccd2/SelectorProducer@44aa2e13/PRODUCING/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=4,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-07-27T15:28:23.158+02:00
15:28:23.158 [qtp1002031672-34] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/1189651769@256a0d95
15:28:23.159 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@7a83ccd2/SelectorProducer@44aa2e13/IDLE/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-07-27T15:28:23.158+02:00
15:28:23.159 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@599a9cb2{STOPPED} id=2 keys=-1 selected=-1 updates=0
15:28:23.159 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@dbddbe3{STARTED} id=1 keys=1 selected=0 updates=0
15:28:23.159 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@baa9ce4 on ManagedSelector@dbddbe3{STOPPING} id=1 keys=1 selected=0 updates=0
15:28:23.160 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@dbddbe3{STOPPING} id=1 keys=1 selected=0 updates=1
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken with none selected
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken up from select, 0/0/1 selected
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 processing 0 keys, 1 updates
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@baa9ce4
15:28:23.160 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 1 connections on ManagedSelector@dbddbe3{STOPPING} id=1 keys=1 selected=0 updates=0
15:28:23.160 [qtp1002031672-33] DEBUG o.eclipse.jetty.io.AbstractEndPoint - close SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=14/30000}{io=1/1,kio=1,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.161 [qtp1002031672-33] DEBUG o.eclipse.jetty.io.AbstractEndPoint - close(null) SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,OPEN,fill=FI,flush=-,to=15/30000}{io=1/1,kio=1,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.161 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,CLOSED,fill=FI,flush=-,to=15/30000}{io=1/1,kio=1,kro=1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.162 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@3bc72bb1{IDLE}->null
java.nio.channels.ClosedChannelException: null
	at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:492) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:353) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:215) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.AbstractConnection.close(AbstractConnection.java:248) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector.closeNoExceptions(ManagedSelector.java:252) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector.access$1400(ManagedSelector.java:61) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$CloseConnections.update(ManagedSelector.java:866) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.processUpdates(ManagedSelector.java:426) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:397) [jetty-io-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:357) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:181) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:132) [jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:786) ~[jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:743) ~[jetty-util-9.4.17.v20190418.jar:9.4.17.v20190418]
	at java.lang.Thread.run(Thread.java:748) ~[na:1.8.0_212]
15:28:23.162 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@7bb61dd4{AC.ReadCB@4a36967c{HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,CLOSED,fill=FI,flush=-,to=16/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@4a36967c[p=HttpParser{s=START,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
15:28:23.163 [qtp1002031672-33] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
15:28:23.163 [qtp1002031672-33] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
15:28:23.163 [qtp1002031672-33] DEBUG o.e.jetty.io.AbstractConnection - HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,CLOSED,fill=-,flush=-,to=17/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@4a36967c[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed java.nio.channels.ClosedChannelException
15:28:23.163 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup ManagedSelector@dbddbe3{STOPPING} id=1 keys=1 selected=0 updates=0
15:28:23.163 [qtp1002031672-33] DEBUG o.e.j.util.thread.QueuedThreadPool - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@204148be
15:28:23.163 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.163 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 waiting with 1 keys
15:28:23.163 [qtp1002031672-39] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@204148be
15:28:23.164 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@5b332439 on ManagedSelector@dbddbe3{STOPPING} id=1 keys=0 selected=0 updates=0
15:28:23.164 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@dbddbe3{STOPPING} id=1 keys=0 selected=0 updates=1
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken with none selected
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 woken up from select, 0/0/0 selected
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@6fbbb102 processing 0 keys, 1 updates
15:28:23.164 [qtp1002031672-39] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,CLOSED,fill=-,flush=-,to=18/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@4a36967c[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@5b332439
15:28:23.164 [qtp1002031672-33] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.164 [qtp1002031672-33] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/1189651769@4b55ff0a
15:28:23.165 [qtp1002031672-39] DEBUG o.e.jetty.io.AbstractConnection - onClose HttpConnection@4a36967c::SocketChannelEndPoint@5cc628e{/127.0.0.1:40290<->/127.0.0.1:41823,CLOSED,fill=-,flush=-,to=18/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@4a36967c[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@2c8845a6{s=START}]=>HttpChannelOverHttp@b0b9c47{r=1,c=false,c=false/false,a=IDLE,uri=null,age=0}
15:28:23.165 [qtp1002031672-39] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@204148be
15:28:23.165 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@1f84327b/SelectorProducer@39549f33/IDLE/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=5,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2019-07-27T15:28:23.165+02:00
15:28:23.166 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@1f84327b/SelectorProducer@39549f33/IDLE/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=6,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=1,epc=0]@2019-07-27T15:28:23.165+02:00
15:28:23.166 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@dbddbe3{STOPPED} id=1 keys=-1 selected=-1 updates=0
15:28:23.166 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ManagedSelector@6f731759{STARTED} id=0 keys=0 selected=0 updates=0
15:28:23.166 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$CloseConnections@59262a90 on ManagedSelector@6f731759{STOPPING} id=0 keys=0 selected=0 updates=0
15:28:23.166 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f731759{STOPPING} id=0 keys=0 selected=0 updates=1
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 woken with none selected
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 woken up from select, 0/0/0 selected
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 processing 0 keys, 1 updates
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$CloseConnections@59262a90
15:28:23.167 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Closing 0 connections on ManagedSelector@6f731759{STOPPING} id=0 keys=0 selected=0 updates=0
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 waiting with 0 keys
15:28:23.168 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$StopSelector@78479f2b on ManagedSelector@6f731759{STOPPING} id=0 keys=0 selected=0 updates=0
15:28:23.168 [main] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@6f731759{STOPPING} id=0 keys=0 selected=0 updates=1
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 woken with none selected
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 woken up from select, 0/0/0 selected
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@cf88f43 processing 0 keys, 1 updates
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.ManagedSelector$StopSelector@78479f2b
15:28:23.168 [qtp1002031672-32] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
15:28:23.168 [qtp1002031672-32] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.io.ManagedSelector$$Lambda$28/1189651769@3a1b36a1
15:28:23.169 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping EatWhatYouKill@2c3158e0/SelectorProducer@412ebe64/IDLE/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-07-27T15:28:23.169+02:00
15:28:23.169 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED EatWhatYouKill@2c3158e0/SelectorProducer@412ebe64/IDLE/p=false/QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}][pc=0,pic=0,pec=0,epc=0]@2019-07-27T15:28:23.169+02:00
15:28:23.169 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ManagedSelector@6f731759{STOPPED} id=0 keys=-1 selected=-1 updates=0
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED SelectorManager@ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping HttpConnectionFactory@365afe87[HTTP/1.1]
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED HttpConnectionFactory@365afe87[HTTP/1.1]
15:28:23.170 [main] INFO  o.e.jetty.server.AbstractConnector - Stopped ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ServerConnector@21e45a6f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
15:28:23.170 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping Server@160e99e0{STOPPING}[9.4.17.v20190418]
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping DefaultSessionIdManager@10a18e3e{STARTED}[worker=node0]
15:28:23.170 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping HouseKeeper@69364b2d{STARTED}[interval=660000, ownscheduler=false]
15:28:23.171 [main] INFO  org.eclipse.jetty.server.session - node0 Stopped scavenging
15:28:23.171 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED HouseKeeper@69364b2d{STOPPED}[interval=660000, ownscheduler=false]
15:28:23.171 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED DefaultSessionIdManager@10a18e3e{STOPPED}[worker=node0]
15:28:23.171 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ErrorHandler@1c628f6a{STARTED}
15:28:23.171 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping ErrorHandler@1c628f6a{STOPPING}
15:28:23.171 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ErrorHandler@1c628f6a{STOPPED}
15:28:23.171 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping o.e.j.s.ServletContextHandler@3cc053{/,null,SHUTDOWN}
15:28:23.172 [main] DEBUG o.e.j.s.h.AbstractHandlerContainer - Graceful shutdown o.e.j.s.ServletContextHandler@3cc053{/,null,SHUTDOWN} by 
15:28:23.172 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping o.e.j.s.ServletContextHandler@3cc053{/,null,UNAVAILABLE}
15:28:23.172 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.SessionHandler59295001==dftMaxIdleSec=-1
15:28:23.172 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.DefaultSessionCache@6f9ab79d[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
15:28:23.172 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.server.session.NullSessionDataStore@64ae105d[passivating=false,graceSec=3600]
15:28:23.172 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.NullSessionDataStore@64ae105d[passivating=false,graceSec=3600]
15:28:23.172 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.DefaultSessionCache@6f9ab79d[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false]
15:28:23.172 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping org.eclipse.jetty.server.session.SessionHandler59295001==dftMaxIdleSec=-1
15:28:23.173 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ServletHandler@7fbd3e75{STARTED}
15:28:23.173 [main] DEBUG o.e.j.server.handler.AbstractHandler - stopping ServletHandler@7fbd3e75{STOPPING}
15:28:23.173 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-4792f119@38909063==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
15:28:23.173 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.servlet.ServletHandler$Default404Servlet-4792f119@38909063==org.eclipse.jetty.servlet.ServletHandler$Default404Servlet,jsp=null,order=-1,inst=false,async=true
15:28:23.173 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping org.glassfish.jersey.servlet.ServletContainer-2ce03e86@4714ca3a==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
15:28:23.173 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.glassfish.jersey.servlet.ServletContainer-2ce03e86@4714ca3a==org.glassfish.jersey.servlet.ServletContainer,jsp=null,order=-1,inst=true,async=true
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ServletHandler@7fbd3e75{STOPPED}
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED org.eclipse.jetty.server.session.SessionHandler59295001==dftMaxIdleSec=-1
15:28:23.174 [main] INFO  o.e.j.server.handler.ContextHandler - Stopped o.e.j.s.ServletContextHandler@3cc053{/,null,UNAVAILABLE}
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED o.e.j.s.ServletContextHandler@3cc053{/,null,UNAVAILABLE}
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ScheduledExecutorScheduler@4f3e9fbb{STARTED}
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ScheduledExecutorScheduler@4f3e9fbb{STOPPED}
15:28:23.174 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping QueuedThreadPool[qtp1002031672]@3bb9ca38{STARTED,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}]
15:28:23.175 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Stopping QueuedThreadPool[qtp1002031672]@3bb9ca38{STOPPING,8<=8<=500,i=7,q=0}[ReservedThreadExecutor@9b47400{s=1/6,p=0}]
15:28:23.175 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - stopping ReservedThreadExecutor@9b47400{s=1/6,p=0}
15:28:23.175 [main] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@9b47400{s=0/6,p=0}@6543da72 offer STOP!
15:28:23.176 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED ReservedThreadExecutor@9b47400{s=0/6,p=0}
15:28:23.176 [qtp1002031672-37] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@9b47400{s=0/6,p=0}@6543da72 task=STOP!
15:28:23.176 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp1002031672-33,5,main] for 14999
15:28:23.176 [qtp1002031672-37] DEBUG o.e.j.u.t.ReservedThreadExecutor - ReservedThreadExecutor@9b47400{s=0/6,p=0}@6543da72 Exited
15:28:23.176 [qtp1002031672-37] DEBUG o.e.j.util.thread.QueuedThreadPool - ran ReservedThreadExecutor@9b47400{s=0/6,p=0}@6543da72
15:28:23.176 [qtp1002031672-36] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.176 [qtp1002031672-36] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-38] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-35] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-38] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-35] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-34] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-34] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-33] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-33] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [qtp1002031672-39] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.177 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp1002031672-37,5,] for 14998
15:28:23.178 [qtp1002031672-39] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.178 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp1002031672-39,5,main] for 14998
15:28:23.178 [main] DEBUG o.e.j.util.thread.QueuedThreadPool - Waiting for Thread[qtp1002031672-32,5,main] for 14998
15:28:23.178 [qtp1002031672-32] DEBUG o.e.j.util.thread.QueuedThreadPool - run org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.178 [qtp1002031672-32] DEBUG o.e.j.util.thread.QueuedThreadPool - ran org.eclipse.jetty.util.thread.QueuedThreadPool$$Lambda$51/725931728@118462bf
15:28:23.178 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED QueuedThreadPool[qtp1002031672]@3bb9ca38{STOPPED,8<=0<=500,i=0,q=0}[org.eclipse.jetty.util.thread.TryExecutor$$Lambda$1/762476028@55182842]
15:28:23.178 [main] DEBUG o.e.j.u.component.AbstractLifeCycle - STOPPED Server@160e99e0{STOPPED}[9.4.17.v20190418]
15:28:23.179 [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
15:28:23.179 [main] DEBUG c.c.cloudstore.core.io.LockFileImpl - [4b425577]release: releasing underlying FileLock. lockCounter=0
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.378 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.7-SNAPSHOT.jar
[INFO] 
[INFO] --- maven-dependency-plugin:2.8:copy-dependencies (copy-dependencies) @ co.codewizards.cloudstore.client ---
[INFO] Copying jaxb-core-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jaxb-core-2.3.0.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.client-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.client-0.10.7-SNAPSHOT.jar
[INFO] Copying jetty-util-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-util-9.4.17.v20190418.jar
[INFO] Copying jetty-servlet-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-servlet-9.4.17.v20190418.jar
[INFO] Copying jersey-container-servlet-core-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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-continuation-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-continuation-9.4.17.v20190418.jar
[INFO] Copying bcprov-jdk15on-161b03.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/bcprov-jdk15on-161b03.jar
[INFO] Copying cglib-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/cglib-2.2.0-b21.jar
[INFO] Copying co.codewizards.cloudstore.core.oio.nio-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core.oio.nio-0.10.7-SNAPSHOT.jar
[INFO] Copying co.codewizards.cloudstore.core-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.core-0.10.7-SNAPSHOT.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 jaxb-api-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jaxb-api-2.3.0.jar
[INFO] Copying jetty-http-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-http-9.4.17.v20190418.jar
[INFO] Copying jersey-server-2.5.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jersey-server-2.5.1.jar
[INFO] Copying jetty-security-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-security-9.4.17.v20190418.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 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 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.updater-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.updater-0.10.7-SNAPSHOT.jar
[INFO] Copying datanucleus-api-jdo-4.0.5.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-api-jdo-4.0.5.jar
[INFO] Copying co.codewizards.cloudstore.ls.server.cproc-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server.cproc-0.10.7-SNAPSHOT.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 co.codewizards.cloudstore.ls.server-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.server-0.10.7-SNAPSHOT.jar
[INFO] Copying co.codewizards.cloudstore.ls.core-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.core-0.10.7-SNAPSHOT.jar
[INFO] Copying co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.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 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 commons-compress-1.18.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/commons-compress-1.18.jar
[INFO] Copying hk2-locator-2.2.0-b21.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/hk2-locator-2.2.0-b21.jar
[INFO] Copying jetty-servlets-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-servlets-9.4.17.v20190418.jar
[INFO] Copying jetty-server-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-server-9.4.17.v20190418.jar
[INFO] Copying co.codewizards.cloudstore.rest.shared-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.shared-0.10.7-SNAPSHOT.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 activation-1.1.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/activation-1.1.1.jar
[INFO] Copying co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.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 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 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 datanucleus-rdbms-4.0.12.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-rdbms-4.0.12.jar
[INFO] Copying datanucleus-core-4.0.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/datanucleus-core-4.0.7.jar
[INFO] Copying osgi-resource-locator-1.0.1.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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 jetty-xml-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-xml-9.4.17.v20190418.jar
[INFO] Copying logback-core-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/logback-core-1.0.13.jar
[INFO] Copying jaxb-impl-2.3.0.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jaxb-impl-2.3.0.jar
[INFO] Copying logback-classic-1.0.13.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/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 jetty-io-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-io-9.4.17.v20190418.jar
[INFO] Copying slf4j-api-1.7.7.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/slf4j-api-1.7.7.jar
[INFO] Copying jetty-webapp-9.4.17.v20190418.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/jetty-webapp-9.4.17.v20190418.jar
[INFO] Copying bcpg-jdk15on-161b03.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/bcpg-jdk15on-161b03.jar
[INFO] Copying co.codewizards.cloudstore.ls.rest.server-0.10.7-SNAPSHOT.jar to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/dependency/co.codewizards.cloudstore.ls.rest.server-0.10.7-SNAPSHOT.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.7-SNAPSHOT-bin.tar.gz
[INFO] Building zip: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-bin.zip
[INFO] 
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.client ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/client already added, skipping
[INFO] 
[INFO] --- maven-install-plugin:2.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.7-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT.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.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-bin.tar.gz to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-bin.tar.gz
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-bin.zip to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-bin.zip
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.client/target/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.client ---
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 284.4 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11.jar (44 KB at 6234.5 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11.pom (4 KB)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (400 B at 65.1 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1421.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/maven-metadata.xml (400 B at 390.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-bin.tar.gz
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-bin.tar.gz (21667 KB at 49579.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1421.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-bin.zip
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-bin.zip (23984 KB at 54016.2 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1421.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/co.codewizards.cloudstore.client-0.10.7-20190727.132827-11-sources.jar (26 KB at 25936.5 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.client/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1421.9 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