SuccessConsole Output

Skipping 1,688 KB.. Full Log
epoToRepoSync - syncDirectory: path=''
16:07:37.565 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/#4'
16:07:37.597 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1 {11 11ä11#+} 1'
16:07:37.607 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [88a2ab2]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:37.608 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [88a2ab2]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 514 ms.
16:07:37.620 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 30.33%
16:07:37.620 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
16:07:37.636 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [88a2ab2]getCloseDeferredMillis: closeDeferredMillis=0
16:07:37.636 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [88a2ab2]close: Closing real LocalRepoManager immediately.
16:07:37.636 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [88a2ab2]_close: Shutting down real LocalRepoManager.
16:07:37.638 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1 {11 11ä11#+} 1'
16:07:37.673 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
16:07:37.684 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:37.684 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:37.708 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/5#'
16:07:37.758 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 35.59%
16:07:37.770 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 40.86%
16:07:37.771 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/#b'
16:07:37.831 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c31ee019aa2758b5290988a908e0dc7f763ab5cd' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/#b
16:07:37.929 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:37.936 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/#b'
16:07:38.047 [qtp1483371737-31] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:38.184 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:38.185 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 499 ms.
16:07:38.202 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 731175 bytes in 264 ms. path='/#4/#b'
16:07:38.226 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_c691e3d4c979adbf07e3b97e33fdf3c89ed246f6_0 into destFile #b
16:07:38.232 [qtp1483371737-31] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_40fa37ec00c761c7dbb6ebdee6d4a260b922f5f4_0 into destFile zzz
16:07:38.342 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:38.344 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]getCloseDeferredMillis: closeDeferredMillis=0
16:07:38.345 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]close: Closing real LocalRepoManager immediately.
16:07:38.345 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [453767a5]_close: Shutting down real LocalRepoManager.
16:07:38.385 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/a'
16:07:38.436 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=16, fromRepositoryId="FileInProgressMarker", toRepositoryId="16", path="7095d243-aed9-4e1e-a9da-5910cf2b8faa"] on repo=7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:38.459 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='48a4c7d02df9f8d817a4c6ef08373ff49c42611e' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/a
16:07:38.465 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 72.59%
16:07:38.493 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d localRepositoryRevisionSynced=89
16:07:38.501 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:38.504 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/a'
16:07:38.511 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:38.512 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:38.819 [qtp1483371737-32] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:38.862 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 940124 bytes in 358 ms. path='/#4/a'
16:07:38.887 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:38.952 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:38.960 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/c+'
16:07:39.009 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:39.009 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 496 ms.
16:07:39.040 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]getCloseDeferredMillis: closeDeferredMillis=0
16:07:39.040 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]close: Closing real LocalRepoManager immediately.
16:07:39.041 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [54f35610]_close: Shutting down real LocalRepoManager.
16:07:39.062 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='de0a9f5770d23f0434c600703a08f0ffc1ed4dc1' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/c+
16:07:39.069 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:39.069 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:39.121 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:39.123 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/c+'
16:07:39.297 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 331830 bytes in 174 ms. path='/#4/c+'
16:07:39.315 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_8a30244b83c6cac3c79f8f95d30f96579c0f4c0b_0 into destFile c+
16:07:39.366 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:39.390 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/d$'
16:07:39.392 [qtp1483371737-32] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:39.466 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0b8f8fa322e5fd7124965ff415d636499697c26c' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/d$
16:07:39.559 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:39.560 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 489 ms.
16:07:39.562 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:39.587 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/d$'
16:07:39.661 [qtp1483371737-32] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa transaction.localRevision=15 remoteFromLocalRevision=89
16:07:39.668 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]getCloseDeferredMillis: closeDeferredMillis=0
16:07:39.669 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]close: Closing real LocalRepoManager immediately.
16:07:39.670 [qtp1483371737-32] INFO  c.c.c.l.LocalRepoManagerImpl - [55c0351d]_close: Shutting down real LocalRepoManager.
16:07:39.724 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f4d653bc-6b72-4556-b432-4058c491cc9d from='https://localhost:2833/f4d653bc-6b72-4556-b432-4058c491cc9d' toID=7095d243-aed9-4e1e-a9da-5910cf2b8faa to='target/repo/k974jnlc-g05no-local', fromRepoLocalSync=false
16:07:39.724 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.null.xml.gz
16:07:39.739 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:39.739 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:39.741 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 268280 bytes in 154 ms. path='/#4/d$'
16:07:39.755 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_a93a212f7eca012dceb852f364cc2c34026f0dd8_0 into destFile d$
16:07:39.804 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:39.829 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/a'
16:07:39.930 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1b872922f7341940f6447f25dd392641a8c521f3' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/a
16:07:39.970 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:39.977 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/a'
16:07:40.015 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 42416 bytes in 37 ms. path='/1 {11 11ä11#+} 1/a'
16:07:40.025 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:40.056 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.132 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/b'
16:07:40.136 [qtp1483371737-31] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:40.248 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e83728c7adb61973ade39d9cc19c08676629c972' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/b
16:07:40.303 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.310 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/b'
16:07:40.318 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:40.319 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 578 ms.
16:07:40.355 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]getCloseDeferredMillis: closeDeferredMillis=0
16:07:40.356 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]close: Closing real LocalRepoManager immediately.
16:07:40.356 [qtp1483371737-31] INFO  c.c.c.l.LocalRepoManagerImpl - [1c9146f5]_close: Shutting down real LocalRepoManager.
16:07:40.408 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:40.408 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:40.506 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 667800 bytes in 196 ms. path='/1 {11 11ä11#+} 1/b'
16:07:40.514 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:07:40.566 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.576 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/c'
16:07:40.636 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a6e4ef92213f2a4b7bc614fd43fffc1cd9217195' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/c
16:07:40.665 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.668 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/c'
16:07:40.697 [pool-4-thread-1] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:40.713 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 109344 bytes in 45 ms. path='/1 {11 11ä11#+} 1/c'
16:07:40.720 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
16:07:40.749 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.758 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/a'
16:07:40.840 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2b745300cd43e519272582865757948c092d003f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/a
16:07:40.905 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:40.906 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 496 ms.
16:07:40.910 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.913 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/a'
16:07:40.945 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 12240 bytes in 31 ms. path='/2/1 {11 11ä11#+} 1/a'
16:07:40.954 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:40.978 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:40.989 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/b'
16:07:41.006 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:41.010 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:41.010 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:41.010 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:41.010 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=15
16:07:41.021 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:41.030 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:41.031 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:41.031 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:41.031 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:41.032 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]getCloseDeferredMillis: closeDeferredMillis=0
16:07:41.032 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]close: Closing real LocalRepoManager immediately.
16:07:41.032 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [525e6c1c]_close: Shutting down real LocalRepoManager.
16:07:41.056 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d4b0323daa6ac7bf2b808eda2ffc76da61815bbc' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/b
16:07:41.096 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:41.100 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/b'
16:07:41.116 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.null.xml.gz
16:07:41.117 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 88.31%
16:07:41.117 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 99.50%
16:07:41.126 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:41.127 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:41.294 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 670502 bytes in 193 ms. path='/2/1 {11 11ä11#+} 1/b'
16:07:41.310 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:07:41.359 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:41.389 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
16:07:41.457 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='21f38860e78844b5432f0f50c6c40b688275e9e1' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
16:07:41.543 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:41.545 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
16:07:41.601 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:41.682 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 198072 bytes in 136 ms. path='/2/a'
16:07:41.692 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:41.736 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:41.758 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
16:07:41.843 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:41.844 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 716 ms.
16:07:41.846 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2ba98af4b069190d6eee65c969046f9939052cb7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
16:07:41.884 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]getCloseDeferredMillis: closeDeferredMillis=0
16:07:41.884 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]close: Closing real LocalRepoManager immediately.
16:07:41.884 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [29558ccb]_close: Shutting down real LocalRepoManager.
16:07:41.902 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:41.904 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
16:07:41.923 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:41.924 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:41.961 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 80224 bytes in 56 ms. path='/3/a'
16:07:41.974 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:41.995 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.003 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
16:07:42.069 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b949127ce02a8398b5d07989ae0732c338838f3d' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
16:07:42.111 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.114 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
16:07:42.272 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:42.311 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 452673 bytes in 197 ms. path='/3/b'
16:07:42.320 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:07:42.342 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [759068b6]initPersistenceManagerFactory: repositoryId=4439d62a-16a6-41de-a383-590e0206a0fe
16:07:42.363 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.372 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
16:07:42.374 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [759068b6]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 9654 ms.
16:07:42.439 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5aa3637ef334e0edde11d70b29bb0906e96dcde1' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
16:07:42.459 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:42.460 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 535 ms.
16:07:42.487 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.489 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
16:07:42.512 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 18037 bytes in 23 ms. path='/3/c'
16:07:42.518 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
16:07:42.540 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.546 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
16:07:42.590 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0a3ba5bf5dc14238579a3c0d3c5cc41131d77adc' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
16:07:42.600 [qtp1483371737-27] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=15
16:07:42.608 [closeDeferredTimer-19c1f6f4-1] INFO  c.c.c.l.LocalRepoManagerImpl - [19c1f6f4]_close: Shutting down real LocalRepoManager.
16:07:42.609 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]getCloseDeferredMillis: closeDeferredMillis=0
16:07:42.610 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]close: Closing real LocalRepoManager immediately.
16:07:42.610 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [35a594ed]_close: Shutting down real LocalRepoManager.
16:07:42.611 [closeDeferredTimer-67292fc7-1] INFO  c.c.c.l.LocalRepoManagerImpl - [67292fc7]_close: Shutting down real LocalRepoManager.
16:07:42.644 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [759068b6]getCloseDeferredMillis: closeDeferredMillis=20000
16:07:42.645 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [759068b6]close: Deferring shut down of real LocalRepoManager 20000 ms.
16:07:42.673 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d transaction.localRevision=94 remoteFromLocalRevision=15
16:07:42.676 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 100.00%
16:07:42.676 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [20425fab]getCloseDeferredMillis: closeDeferredMillis=0
16:07:42.676 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [20425fab]close: Closing real LocalRepoManager immediately.
16:07:42.677 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [20425fab]_close: Shutting down real LocalRepoManager.
16:07:42.677 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.689 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
16:07:42.785 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 299280 bytes in 96 ms. path='/3/d'
16:07:42.794 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
16:07:42.838 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:42.857 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 44.48%
16:07:42.857 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/5#/e'
[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 359.588 s - in co.codewizards.cloudstore.test.LocalServerClientIT
16:07:42.897 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@528854b9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
16:07:42.899 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:07:42.920 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@33e853fd{/,null,UNAVAILABLE}
16:07:42.929 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1ababe249189ac00fe022467cb068aae8bf2ad61' to.sha1='<NoInstanceOf_NormalFileDto>'): /5#/e
16:07:42.929 [Thread-3] INFO  c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/jvm/rOSNUPwtRVHh0_TLrXGmdg/.cloudstore/localServerRunning.deleteToStop
16:07:42.936 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@255c50aa{SSL,[ssl, http/1.1]}{0.0.0.0:31118}
16:07:42.937 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:07:42.955 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@6520c71b{/,null,UNAVAILABLE}
16:07:42.958 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:43.040 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/5#/e'
16:07:43.141 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 467680 bytes in 101 ms. path='/5#/e'
16:07:43.148 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
16:07:43.242 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="b4c1d58b-7bab-4024-9713-600df41990d3"] on repo=83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:43.254 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 49.75%
16:07:43.328 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=3
16:07:43.390 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 transaction.localRevision=84 remoteFromLocalRevision=3
16:07:43.407 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 from='target/repo/k974k3qu-wgp9w-local' toID=b4c1d58b-7bab-4024-9713-600df41990d3 to='https://localhost:13473/b4c1d58b-7bab-4024-9713-600df41990d3'
16:07:43.467 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:43.472 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:43.472 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: b4c1d58b-7bab-4024-9713-600df41990d3
16:07:43.478 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:43.478 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=84
16:07:43.487 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:43.496 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:43.496 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:43.496 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:43.496 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:43.496 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 63.43%
16:07:43.497 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
16:07:43.518 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 localRepositoryRevisionSynced=84
16:07:43.573 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 transaction.localRevision=6 remoteFromLocalRevision=84
16:07:43.606 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
16:07:43.606 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Restoring backup: 'target/repo/k974jnld-vlnfp-remote.bak' => 'target/repo/k974jnld-vlnfp-remote'
16:07:43.644 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b4c1d58b-7bab-4024-9713-600df41990d3 from='https://localhost:13473/b4c1d58b-7bab-4024-9713-600df41990d3' toID=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 to='target/repo/k974k3qu-wgp9w-local', fromRepoLocalSync=false
16:07:43.646 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.null.xml.gz
16:07:43.672 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Restored backup: 'target/repo/k974jnld-vlnfp-remote.bak' => 'target/repo/k974jnld-vlnfp-remote'
16:07:43.672 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
16:07:43.674 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local'
16:07:43.674 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local' locked successfully.
16:07:43.681 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:43.685 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: b4c1d58b-7bab-4024-9713-600df41990d3
16:07:43.686 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:43.686 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:43.686 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=3 localRepositoryRevisionInProgress=6
16:07:43.688 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:43.690 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:43.691 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:43.691 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:43.691 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:43.702 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.null.xml.gz
16:07:43.703 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
16:07:43.703 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
16:07:43.720 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=6
16:07:43.765 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 transaction.localRevision=87 remoteFromLocalRevision=6
16:07:43.806 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
16:07:44.000 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 0.50%
16:07:44.001 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b4c1d58b-7bab-4024-9713-600df41990d3 from='https://localhost:13473/b4c1d58b-7bab-4024-9713-600df41990d3' toID=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 to='target/repo/k974k3qu-wgp9w-local', fromRepoLocalSync=true
16:07:44.001 [pool-8-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 83e5bbb2-6ab0-430b-818d-1a60e02b6e05 ('target/repo/k974k3qu-wgp9w-local')
16:07:44.017 [pool-8-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 12.94%
16:07:44.055 [pool-8-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 19.03%
16:07:44.101 [pool-8-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 24.06%
16:07:44.102 [main] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:44.133 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.6.xml.gz
16:07:44.146 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
16:07:44.148 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
16:07:44.153 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 55.72%
16:07:44.165 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 64.70%
16:07:44.180 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
16:07:44.196 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 84.30%
16:07:44.294 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]initPersistenceManagerFactory: repositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:44.295 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 619 ms.
16:07:44.324 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 94.10%
16:07:44.350 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
16:07:44.366 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:44.373 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: b4c1d58b-7bab-4024-9713-600df41990d3
16:07:44.373 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:44.373 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:44.374 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=6 localRepositoryRevisionInProgress=9
16:07:44.397 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 2 ModificationDtos took 0 ms.
16:07:44.421 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:44.421 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:44.421 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:44.421 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:44.437 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.6.xml.gz
16:07:44.437 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 30.16%
16:07:44.438 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
16:07:44.460 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1 {11 11ä11#+} 1'
16:07:44.478 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Moving from '/2/1 {11 11ä11#+} 1/b' to '/2/b'
16:07:44.624 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1 {11 11ä11#+} 1/b'
16:07:44.636 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 37.16%
16:07:44.638 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 42.75%
16:07:44.638 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/b'
16:07:44.692 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - File is already equal on destination side (sha1='d4b0323daa6ac7bf2b808eda2ffc76da61815bbc'): /2/b
16:07:44.693 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 48.35%
16:07:44.704 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:44.704 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:44.766 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=9
16:07:44.781 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 transaction.localRevision=91 remoteFromLocalRevision=9
16:07:44.789 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 from='target/repo/k974k3qu-wgp9w-local' toID=b4c1d58b-7bab-4024-9713-600df41990d3 to='https://localhost:13473/b4c1d58b-7bab-4024-9713-600df41990d3'
16:07:44.829 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:44.832 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:44.832 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: b4c1d58b-7bab-4024-9713-600df41990d3
16:07:44.832 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:44.833 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 localRepositoryRevisionSynced=84 localRepositoryRevisionInProgress=91
16:07:44.859 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 2 ModificationDtos took 0 ms.
16:07:44.862 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:44.862 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:44.862 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:44.862 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:44.863 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 57.84%
16:07:44.864 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Moving from '/2/1 {11 11ä11#+} 1/b' to '/2/b'
16:07:44.878 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1 {11 11ä11#+} 1/b'
16:07:44.919 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 69.03%
16:07:44.920 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
16:07:44.968 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 localRepositoryRevisionSynced=91
16:07:44.984 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 transaction.localRevision=12 remoteFromLocalRevision=91
16:07:44.993 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b4c1d58b-7bab-4024-9713-600df41990d3 from='https://localhost:13473/b4c1d58b-7bab-4024-9713-600df41990d3' toID=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 to='target/repo/k974k3qu-wgp9w-local', fromRepoLocalSync=false
16:07:44.994 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.null.xml.gz
16:07:44.999 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:45.019 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:45.022 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: b4c1d58b-7bab-4024-9713-600df41990d3
16:07:45.022 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 83e5bbb2-6ab0-430b-818d-1a60e02b6e05
16:07:45.022 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:45.022 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=9 localRepositoryRevisionInProgress=12
16:07:45.024 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:45.026 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:45.026 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:45.026 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:45.026 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:45.039 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974k3qu-wgp9w-local/.cloudstore-repo/tmp/ChangeSetDto.b4c1d58b-7bab-4024-9713-600df41990d3.null.xml.gz
16:07:45.039 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
16:07:45.039 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
16:07:45.059 [qtp480959907-33] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 remoteRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 localRepositoryRevisionSynced=12
16:07:45.076 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=83e5bbb2-6ab0-430b-818d-1a60e02b6e05 remoteRepositoryId=b4c1d58b-7bab-4024-9713-600df41990d3 transaction.localRevision=94 remoteFromLocalRevision=12
16:07:45.079 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
16:07:45.143 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:45.144 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 438 ms.
16:07:45.168 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [13552290]getCloseDeferredMillis: closeDeferredMillis=20000
16:07:45.168 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [13552290]close: Deferring shut down of real LocalRepoManager 20000 ms.
16:07:45.169 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e864fa4]getCloseDeferredMillis: closeDeferredMillis=20000
16:07:45.169 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e864fa4]close: Deferring shut down of real LocalRepoManager 20000 ms.
16:07:45.171 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]getCloseDeferredMillis: closeDeferredMillis=0
16:07:45.171 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]close: Closing real LocalRepoManager immediately.
16:07:45.172 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [24ece8fe]_close: Shutting down real LocalRepoManager.
16:07:45.172 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local'
16:07:45.179 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local' locked successfully.
16:07:45.223 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 0.50%
16:07:45.231 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f4d653bc-6b72-4556-b432-4058c491cc9d from='https://localhost:2833/f4d653bc-6b72-4556-b432-4058c491cc9d' toID=7095d243-aed9-4e1e-a9da-5910cf2b8faa to='target/repo/k974jnlc-g05no-local', fromRepoLocalSync=true
16:07:45.231 [pool-9-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 7095d243-aed9-4e1e-a9da-5910cf2b8faa ('target/repo/k974jnlc-g05no-local')
16:07:45.273 [pool-9-thread-1] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 12.94%
16:07:45.335 [pool-9-thread-1] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 19.03%
16:07:45.387 [pool-9-thread-1] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 24.15%
16:07:45.445 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.15.xml.gz
16:07:45.453 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:45.454 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:45.816 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:45.953 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:45.954 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 499 ms.
16:07:45.994 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]getCloseDeferredMillis: closeDeferredMillis=0
16:07:45.994 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]close: Closing real LocalRepoManager immediately.
16:07:45.994 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3f89ecb]_close: Shutting down real LocalRepoManager.
16:07:46.129 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa serverRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d renewalDateTime=2020-04-19T14:37:16.621Z expiryDateTime=2020-04-19T15:07:16.621Z
16:07:46.135 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:46.135 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:46.416 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:46.540 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:46.540 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 403 ms.
16:07:46.570 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]getCloseDeferredMillis: closeDeferredMillis=0
16:07:46.570 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]close: Closing real LocalRepoManager immediately.
16:07:46.570 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [79ef320]_close: Shutting down real LocalRepoManager.
16:07:46.808 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:46.809 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:47.100 [pool-4-thread-1] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:47.273 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:47.274 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 462 ms.
16:07:47.297 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
16:07:47.313 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
16:07:47.339 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 55.72%
16:07:47.369 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 64.70%
16:07:47.378 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
16:07:47.403 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 84.30%
16:07:47.440 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 94.10%
16:07:47.480 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
16:07:47.497 [pool-4-thread-1] WARN  c.c.c.l.t.ChangeSetDtoBuilder - prepareLastSyncToRemoteRepo: Enabling resyncMode! lastSyncToRemoteRepoLocalRepositoryRevisionSynced=15 overwrites lastSyncToRemoteRepo.localRepositoryRevisionSynced=6
16:07:47.527 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:47.530 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:47.530 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:47.530 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:47.530 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=15 localRepositoryRevisionInProgress=8
16:07:47.539 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:47.549 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:47.550 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:47.550 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:47.550 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:47.550 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]getCloseDeferredMillis: closeDeferredMillis=0
16:07:47.551 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]close: Closing real LocalRepoManager immediately.
16:07:47.551 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [13b09320]_close: Shutting down real LocalRepoManager.
16:07:47.611 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.15.xml.gz
16:07:47.612 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 38.56%
16:07:47.612 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 49.75%
16:07:47.619 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:47.619 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:47.897 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:48.040 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:48.040 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 420 ms.
16:07:48.067 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]getCloseDeferredMillis: closeDeferredMillis=0
16:07:48.067 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]close: Closing real LocalRepoManager immediately.
16:07:48.067 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [67538c34]_close: Shutting down real LocalRepoManager.
16:07:48.106 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:48.106 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:48.342 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:48.501 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:48.502 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 394 ms.
16:07:48.596 [qtp1483371737-27] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=8
16:07:48.601 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]getCloseDeferredMillis: closeDeferredMillis=0
16:07:48.601 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]close: Closing real LocalRepoManager immediately.
16:07:48.601 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19bf2d18]_close: Shutting down real LocalRepoManager.
16:07:48.788 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d transaction.localRevision=95 remoteFromLocalRevision=8
16:07:48.790 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=7095d243-aed9-4e1e-a9da-5910cf2b8faa from='target/repo/k974jnlc-g05no-local' toID=f4d653bc-6b72-4556-b432-4058c491cc9d to='https://localhost:2833/f4d653bc-6b72-4556-b432-4058c491cc9d'
16:07:48.798 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:48.798 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:49.111 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:49.264 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:49.265 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 465 ms.
16:07:49.300 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]getCloseDeferredMillis: closeDeferredMillis=0
16:07:49.300 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]close: Closing real LocalRepoManager immediately.
16:07:49.300 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4c0a6620]_close: Shutting down real LocalRepoManager.
16:07:49.397 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:49.398 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:49.519 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]initPersistenceManagerFactory: repositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:49.523 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2373 ms.
localRoot: k974kl5e-5eu36-local
localRepositoryId: b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:49.558 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5f-2zuy4-remote'
16:07:49.564 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5f-2zuy4-remote' locked successfully.
16:07:49.620 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:49.739 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:49.740 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 338 ms.
16:07:49.771 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]getCloseDeferredMillis: closeDeferredMillis=0
16:07:49.771 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]close: Closing real LocalRepoManager immediately.
16:07:49.771 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [181fc813]_close: Shutting down real LocalRepoManager.
16:07:49.833 [main] WARN  c.c.c.l.t.ChangeSetDtoBuilder - prepareLastSyncToRemoteRepo: Enabling resyncMode! lastSyncToRemoteRepoLocalRepositoryRevisionSynced=84 overwrites lastSyncToRemoteRepo.localRepositoryRevisionSynced=89
16:07:49.852 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:49.857 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:49.857 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:49.857 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:49.857 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d localRepositoryRevisionSynced=84 localRepositoryRevisionInProgress=95
16:07:49.868 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:49.913 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:49.913 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:49.913 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:49.913 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:49.914 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 56.31%
16:07:49.915 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
16:07:49.924 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:49.925 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:50.192 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:50.327 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:50.328 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 402 ms.
16:07:50.355 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]getCloseDeferredMillis: closeDeferredMillis=0
16:07:50.356 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]close: Closing real LocalRepoManager immediately.
16:07:50.356 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7044c385]_close: Shutting down real LocalRepoManager.
16:07:50.475 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:50.476 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:50.740 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:50.934 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:50.935 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 457 ms.
16:07:51.037 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]getCloseDeferredMillis: closeDeferredMillis=0
16:07:51.038 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]close: Closing real LocalRepoManager immediately.
16:07:51.038 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5a71a488]_close: Shutting down real LocalRepoManager.
16:07:51.327 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 62.42%
16:07:51.327 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 68.52%
16:07:51.327 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/zzz'
16:07:51.360 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:51.361 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:51.720 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:51.867 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:51.867 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 504 ms.
16:07:51.900 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]getCloseDeferredMillis: closeDeferredMillis=0
16:07:51.900 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]close: Closing real LocalRepoManager immediately.
16:07:51.900 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [4b7c1172]_close: Shutting down real LocalRepoManager.
16:07:52.748 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:52.749 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:53.024 [pool-4-thread-1] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:53.185 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:53.186 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 435 ms.
16:07:53.235 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]getCloseDeferredMillis: closeDeferredMillis=0
16:07:53.235 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]close: Closing real LocalRepoManager immediately.
16:07:53.235 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [716455c7]_close: Shutting down real LocalRepoManager.
16:07:53.283 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='50e9e01687d84ede972a0f5cc6538576f05db360' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/zzz
Apr 19, 2020 4:07:53 PM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
16:07:53.291 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:53.291 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:53.543 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:53.674 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:53.674 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 381 ms.
16:07:53.702 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]getCloseDeferredMillis: closeDeferredMillis=0
16:07:53.702 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]close: Closing real LocalRepoManager immediately.
16:07:53.702 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [6a21415c]_close: Shutting down real LocalRepoManager.
16:07:53.802 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:53.803 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:54.102 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:54.226 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:54.226 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 420 ms.
16:07:54.394 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]getCloseDeferredMillis: closeDeferredMillis=0
16:07:54.394 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]close: Closing real LocalRepoManager immediately.
16:07:54.394 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [150018a8]_close: Shutting down real LocalRepoManager.
16:07:54.488 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=17, fromRepositoryId="FileInProgressMarker", toRepositoryId="17", path="7095d243-aed9-4e1e-a9da-5910cf2b8faa"] on repo=7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:54.496 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/zzz'
16:07:54.530 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:54.531 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:54.922 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:55.045 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:55.045 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 513 ms.
16:07:55.075 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]getCloseDeferredMillis: closeDeferredMillis=0
16:07:55.075 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]close: Closing real LocalRepoManager immediately.
16:07:55.075 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [3805804e]_close: Shutting down real LocalRepoManager.
16:07:55.101 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:55.102 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:55.335 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:55.468 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:55.468 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 364 ms.
16:07:55.516 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]getCloseDeferredMillis: closeDeferredMillis=0
16:07:55.517 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]close: Closing real LocalRepoManager immediately.
16:07:55.517 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [422037b7]_close: Shutting down real LocalRepoManager.
16:07:55.546 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 79618 bytes in 1050 ms. path='/3/zzz'
Apr 19, 2020 4:07:55 PM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
16:07:55.558 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:55.558 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:55.777 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:55.828 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]initPersistenceManagerFactory: repositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:07:55.833 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2931 ms.
remoteRoot: k974kl5f-2zuy4-remote
remoteRepositoryId: ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:07:55.918 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:55.919 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 359 ms.
16:07:55.946 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]getCloseDeferredMillis: closeDeferredMillis=0
16:07:55.946 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]close: Closing real LocalRepoManager immediately.
16:07:55.946 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [365799bc]_close: Shutting down real LocalRepoManager.
16:07:55.972 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:55.972 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:56.088 [main] INFO  c.c.c.l.s.LocalServer - logMemoryStats: max=3.9 GiB, allocated=136.0 MiB, used=46.4 MiB, available=3.9 GiB
16:07:56.201 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:56.325 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:56.326 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 352 ms.
16:07:56.419 [qtp1483371737-28] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_40fa37ec00c761c7dbb6ebdee6d4a260b922f5f4_0 into destFile zzz
16:07:56.500 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]getCloseDeferredMillis: closeDeferredMillis=0
16:07:56.501 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]close: Closing real LocalRepoManager immediately.
16:07:56.501 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6c94e640]_close: Shutting down real LocalRepoManager.
Successfully requested to connect the following local and remote repositories:

  localRepository.repositoryId = b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
  localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local
  localRepository.publicKeySha1 = 8A:2E:70:52:2A:ED:1F:C9:20:F7:88:74:30:D5:69:11:B6:D9:57:8B

  remoteRepository.repositoryId = ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
  remoteRepository.remoteRoot = https://localhost:13473/ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
  remoteRepository.publicKeySha1 = 52:08:16:DB:FA:5C:27:1E:85:37:3F:6D:1A:35:52:DD:DC:5E:17:E0

Please verify the 'publicKeySha1' fingerprints! If they do not match the fingerprints shown on the server, someone is attacking you and you must cancel this request immediately! To cancel the request, use this command:

  cloudstore dropRepoConnection b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:07:56.589 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=17, fromRepositoryId="FileInProgressMarker", toRepositoryId="17", path="7095d243-aed9-4e1e-a9da-5910cf2b8faa"] on repo=7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:56.600 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 72.59%
16:07:56.615 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d localRepositoryRevisionSynced=95
16:07:56.627 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:56.628 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:56.766 [main] INFO  c.c.c.l.s.LocalServer - logMemoryStats: max=3.9 GiB, allocated=136.0 MiB, used=39.7 MiB, available=3.9 GiB
Successfully accepted the connection request for the following local and remote repositories:

  localRepository.repositoryId = ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
  localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5f-2zuy4-remote
  localRepository.publicKeySha1 = 52:08:16:DB:FA:5C:27:1E:85:37:3F:6D:1A:35:52:DD:DC:5E:17:E0

  remoteRepository.repositoryId = b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
  remoteRepository.publicKeySha1 = 8A:2E:70:52:2A:ED:1F:C9:20:F7:88:74:30:D5:69:11:B6:D9:57:8B

Please verify the 'publicKeySha1' fingerprints! If they do not match the fingerprints shown on the client, someone is attacking you and you must cancel this request immediately! To cancel the request, use this command:

  cloudstore dropRepoConnection ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:56.845 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:56.971 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:56.972 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 343 ms.
16:07:56.999 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]getCloseDeferredMillis: closeDeferredMillis=0
16:07:56.999 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]close: Closing real LocalRepoManager immediately.
16:07:56.999 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [5b45db25]_close: Shutting down real LocalRepoManager.
16:07:57.476 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:57.476 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:57.775 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:57.934 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:57.936 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 458 ms.
16:07:58.006 [qtp1483371737-27] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa transaction.localRevision=15 remoteFromLocalRevision=95
16:07:58.016 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]getCloseDeferredMillis: closeDeferredMillis=0
16:07:58.016 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]close: Closing real LocalRepoManager immediately.
16:07:58.017 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [afa7de8]_close: Shutting down real LocalRepoManager.
16:07:58.049 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f4d653bc-6b72-4556-b432-4058c491cc9d from='https://localhost:2833/f4d653bc-6b72-4556-b432-4058c491cc9d' toID=7095d243-aed9-4e1e-a9da-5910cf2b8faa to='target/repo/k974jnlc-g05no-local', fromRepoLocalSync=false
16:07:58.050 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.null.xml.gz
16:07:58.053 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 0.50%
16:07:58.057 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 from='https://localhost:13473/ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160' toID=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 to='target/repo/k974kl5e-5eu36-local', fromRepoLocalSync=true
16:07:58.057 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:58.057 [pool-9-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 ('target/repo/k974kl5e-5eu36-local')
16:07:58.058 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:58.062 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local/.cloudstore-repo/tmp/ChangeSetDto.ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160.-1.xml.gz
16:07:58.077 [pool-9-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 12.94%
16:07:58.090 [pool-9-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 25.12%
16:07:58.142 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 serverRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 renewalDateTime=2020-04-19T14:37:58.101Z expiryDateTime=2020-04-19T15:07:58.101Z
16:07:58.153 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
16:07:58.156 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
16:07:58.195 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 55.69%
16:07:58.296 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 64.60%
16:07:58.316 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.40%
16:07:58.318 [qtp1483371737-28] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:58.374 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 84.20%
16:07:58.462 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 94.00%
16:07:58.493 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:58.493 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 433 ms.
16:07:58.528 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]getCloseDeferredMillis: closeDeferredMillis=0
16:07:58.528 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]close: Closing real LocalRepoManager immediately.
16:07:58.528 [qtp1483371737-28] INFO  c.c.c.l.LocalRepoManagerImpl - [6f884560]_close: Shutting down real LocalRepoManager.
16:07:58.563 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:58.564 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:58.566 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
16:07:58.601 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:58.604 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:07:58.604 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:58.604 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:58.605 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 remoteRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=3
16:07:58.613 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:58.666 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:58.666 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:58.666 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:58.666 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:58.704 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local/.cloudstore-repo/tmp/ChangeSetDto.ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160.-1.xml.gz
16:07:58.706 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
16:07:58.746 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/#4'
16:07:58.767 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1 {11 11ä11#+} 1'
16:07:58.786 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 30.33%
16:07:58.786 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
16:07:58.809 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1 {11 11ä11#+} 1'
16:07:58.828 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
16:07:58.834 [pool-4-thread-1] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:58.847 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/5#'
16:07:58.875 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 35.59%
16:07:58.876 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 40.86%
16:07:58.877 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/#b'
16:07:58.924 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a7c820700588cdc1753843843885c3a6a031cf56' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/#b
16:07:58.988 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:58.992 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/#b'
16:07:59.024 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 50465 bytes in 32 ms. path='/#4/#b'
16:07:59.032 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_c691e3d4c979adbf07e3b97e33fdf3c89ed246f6_0 into destFile #b
16:07:59.033 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:59.034 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 468 ms.
16:07:59.080 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.093 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/a'
16:07:59.129 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:07:59.132 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:59.132 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 7095d243-aed9-4e1e-a9da-5910cf2b8faa
16:07:59.132 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:07:59.132 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=15
16:07:59.139 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:07:59.143 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='217bc83c43548ceb1c28d0ee16de28662fa3eb7d' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/a
16:07:59.146 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:07:59.146 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:07:59.146 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:07:59.146 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:07:59.147 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]getCloseDeferredMillis: closeDeferredMillis=0
16:07:59.147 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]close: Closing real LocalRepoManager immediately.
16:07:59.147 [pool-4-thread-1] INFO  c.c.c.l.LocalRepoManagerImpl - [7c8f68f]_close: Shutting down real LocalRepoManager.
16:07:59.170 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.172 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/a'
16:07:59.186 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnlc-g05no-local/.cloudstore-repo/tmp/ChangeSetDto.f4d653bc-6b72-4556-b432-4058c491cc9d.null.xml.gz
16:07:59.186 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 88.31%
16:07:59.186 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 99.50%
16:07:59.207 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:59.208 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:59.218 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 95394 bytes in 45 ms. path='/#4/a'
16:07:59.225 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:59.245 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.252 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/c+'
16:07:59.298 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='915b40452b675178e27fa0a029306f5a8796b72c' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/c+
16:07:59.320 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.322 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/c+'
16:07:59.359 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 74940 bytes in 36 ms. path='/#4/c+'
16:07:59.365 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_8a30244b83c6cac3c79f8f95d30f96579c0f4c0b_0 into destFile c+
16:07:59.384 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.390 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/d$'
16:07:59.437 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:59.445 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3d9e03fccc9983b4e0a61960b5f4aa02673639be' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/d$
16:07:59.470 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.473 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/d$'
16:07:59.515 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 76190 bytes in 42 ms. path='/#4/d$'
16:07:59.521 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_a93a212f7eca012dceb852f364cc2c34026f0dd8_0 into destFile d$
16:07:59.540 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.545 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/a'
16:07:59.602 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:07:59.603 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 394 ms.
16:07:59.607 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9cae07a10915f3885524caba3d98d7360eca086d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/a
16:07:59.631 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.634 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]getCloseDeferredMillis: closeDeferredMillis=0
16:07:59.634 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]close: Closing real LocalRepoManager immediately.
16:07:59.634 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [7b44d0ce]_close: Shutting down real LocalRepoManager.
16:07:59.685 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/a'
16:07:59.748 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote'
16:07:59.748 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974jnld-vlnfp-remote' locked successfully.
16:07:59.806 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 383535 bytes in 120 ms. path='/1 {11 11ä11#+} 1/a'
16:07:59.813 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:07:59.843 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.849 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/b'
16:07:59.919 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d1f7eb570d77b0671c4ed4eee4eaa7e8317b358d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/b
16:07:59.939 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:07:59.941 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/b'
16:07:59.976 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 60270 bytes in 35 ms. path='/1 {11 11ä11#+} 1/b'
16:07:59.982 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:07:59.988 [qtp1483371737-27] WARN  D.D.Schema - Retrieved ForeignKey from datastore for table="repository" referencing table repofile but not found internally. Is there some catalog/schema or quoting causing problems?
16:07:59.999 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.006 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/c'
16:08:00.080 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c3084aa52c49cf2ed197226927085a91e428d73c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/c
16:08:00.105 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.107 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/c'
16:08:00.134 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 27280 bytes in 26 ms. path='/1 {11 11ä11#+} 1/c'
16:08:00.141 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]initPersistenceManagerFactory: repositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d
16:08:00.141 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
16:08:00.142 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 393 ms.
16:08:00.156 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.163 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/a'
16:08:00.196 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='db8ebc847282dd27478fadbf1dc04655c9d5038b' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/a
16:08:00.223 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.225 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/a'
16:08:00.233 [qtp1483371737-27] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d remoteRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa localRepositoryRevisionSynced=15
16:08:00.235 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]getCloseDeferredMillis: closeDeferredMillis=0
16:08:00.235 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]close: Closing real LocalRepoManager immediately.
16:08:00.236 [qtp1483371737-27] INFO  c.c.c.l.LocalRepoManagerImpl - [19d18aeb]_close: Shutting down real LocalRepoManager.
16:08:00.280 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7095d243-aed9-4e1e-a9da-5910cf2b8faa remoteRepositoryId=f4d653bc-6b72-4556-b432-4058c491cc9d transaction.localRevision=100 remoteFromLocalRevision=15
16:08:00.283 [main] INFO  c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 100.00%
16:08:00.337 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]getCloseDeferredMillis: closeDeferredMillis=0
16:08:00.337 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]close: Closing real LocalRepoManager immediately.
16:08:00.337 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [514eefc4]_close: Shutting down real LocalRepoManager.
16:08:00.349 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 530469 bytes in 124 ms. path='/2/1 {11 11ä11#+} 1/a'
16:08:00.361 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:08:00.404 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.410 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/b'
[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 221.537 s - in co.codewizards.cloudstore.test.repotorepo.BackupRestoreRepoToRepoSyncIT
16:08:00.451 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@365b963d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
16:08:00.452 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:00.461 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@77d6c95d{/,null,UNAVAILABLE}
16:08:00.468 [Thread-3] INFO  c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/jvm/n7P3Lka6UlsMefq0JnMDIg/.cloudstore/localServerRunning.deleteToStop
16:08:00.472 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@4c9b64b{SSL,[ssl, http/1.1]}{0.0.0.0:2833}
16:08:00.472 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:00.474 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@3256d6b0{/,null,UNAVAILABLE}
16:08:00.477 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='87c70578136e8aec9e6cd8541c89c1e3a4a56f7e' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/b
16:08:00.501 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.503 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/b'
16:08:00.536 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 35199 bytes in 33 ms. path='/2/1 {11 11ä11#+} 1/b'
16:08:00.543 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:08:00.557 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.562 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
16:08:00.641 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bf232cea383b6aca9b937aa181fdddf68363db33' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
16:08:00.671 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.673 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
16:08:00.763 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 466284 bytes in 90 ms. path='/2/a'
16:08:00.769 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:08:00.805 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.813 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
16:08:00.856 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5667a7d5c0a8c556bec6a15b38c3d6c758f3660c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
16:08:00.878 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:00.880 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
16:08:00.963 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 340715 bytes in 83 ms. path='/3/a'
16:08:00.970 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
16:08:01.003 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.011 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
16:08:01.062 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5d5fec5c64a3729b10393930c9814d4c882e329a' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
16:08:01.086 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.088 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
16:08:01.136 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 124416 bytes in 48 ms. path='/3/b'
16:08:01.142 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
16:08:01.161 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.166 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
16:08:01.212 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a55be4910cbe02b79b25adfb8ed244ac7c2668cc' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
16:08:01.240 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.243 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
16:08:01.288 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 136400 bytes in 45 ms. path='/3/c'
16:08:01.296 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
16:08:01.319 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.326 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
16:08:01.380 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='629649ebb143314c90744518f65056d7b8813397' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
16:08:01.405 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.407 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
16:08:01.496 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 434280 bytes in 89 ms. path='/3/d'
16:08:01.502 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
16:08:01.555 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.561 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/5#/e'
16:08:01.612 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='95b6f87feccbcfc819e501e91f5515ca6cec82b3' to.sha1='<NoInstanceOf_NormalFileDto>'): /5#/e
16:08:01.628 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.630 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/5#/e'
16:08:01.656 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 11118 bytes in 26 ms. path='/5#/e'
16:08:01.663 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
16:08:01.677 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160"] on repo=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.683 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 49.42%
16:08:01.729 [qtp480959907-31] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 remoteRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 localRepositoryRevisionSynced=3
16:08:01.746 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 remoteRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 transaction.localRevision=84 remoteFromLocalRevision=3
16:08:01.753 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 from='target/repo/k974kl5e-5eu36-local' toID=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 to='https://localhost:13473/ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160'
16:08:01.807 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:08:01.810 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.810 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:08:01.810 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:08:01.810 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 remoteRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=84
16:08:01.819 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:08:01.837 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:08:01.838 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:08:01.838 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:08:01.838 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:08:01.839 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 63.43%
16:08:01.840 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
16:08:01.861 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 remoteRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 localRepositoryRevisionSynced=84
16:08:01.889 [qtp480959907-31] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 remoteRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 transaction.localRevision=6 remoteFromLocalRevision=84
16:08:01.895 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 from='https://localhost:13473/ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160' toID=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 to='target/repo/k974kl5e-5eu36-local', fromRepoLocalSync=false
16:08:01.896 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local/.cloudstore-repo/tmp/ChangeSetDto.ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160.null.xml.gz
16:08:01.923 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
16:08:01.926 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160
16:08:01.927 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48
16:08:01.927 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
16:08:01.927 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 remoteRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 localRepositoryRevisionSynced=3 localRepositoryRevisionInProgress=6
16:08:01.928 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
16:08:01.930 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
16:08:01.930 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
16:08:01.930 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
16:08:01.930 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
16:08:01.936 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/repo/k974kl5e-5eu36-local/.cloudstore-repo/tmp/ChangeSetDto.ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160.null.xml.gz
16:08:01.937 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
16:08:01.937 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
16:08:01.953 [qtp480959907-31] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 remoteRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 localRepositoryRevisionSynced=6
16:08:01.966 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b5e8f7c6-3e9d-44e9-afd4-a8274ce2ce48 remoteRepositoryId=ee7e7d3f-70e8-4dd7-871f-cb29fd0c8160 transaction.localRevision=87 remoteFromLocalRevision=6
16:08:01.969 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
16:08:02.074 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]getCloseDeferredMillis: closeDeferredMillis=20000
16:08:02.075 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [23471b2f]close: Deferring shut down of real LocalRepoManager 20000 ms.
16:08:02.076 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]getCloseDeferredMillis: closeDeferredMillis=20000
16:08:02.076 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1db4ea47]close: Deferring shut down of real LocalRepoManager 20000 ms.
[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 146.523 s - in co.codewizards.cloudstore.test.repotorepo.BasicRepoToRepoSyncIT
16:08:02.102 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@1fc4b452{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
16:08:02.103 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:02.112 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@7ee0ab1e{/,null,UNAVAILABLE}
16:08:02.119 [Thread-3] INFO  c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/jvm/YflTJ97O2FtZHbcsBx3m1Q/.cloudstore/localServerRunning.deleteToStop
16:08:02.126 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@6beca7a2{SSL,[ssl, http/1.1]}{0.0.0.0:13473}
16:08:02.127 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:02.131 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@6e6c8107{/,null,UNAVAILABLE}
16:08:52.586 [main] ERROR c.c.c.r.c.CloudStoreRestClient - determineBaseUrl: Determining baseURL failed for hostURL='https://localhost:8388/': javax.ws.rs.ProcessingException: javax.net.ssl.SSLException: readHandshakeRecord
16:08:52.586 [main] WARN  c.c.c.r.c.CloudStoreRestClient - execute: invocation failed (will retry): javax.ws.rs.ProcessingException: Determining baseURL failed for hostURL='https://localhost:8388/': javax.ws.rs.ProcessingException: javax.net.ssl.SSLException: readHandshakeRecord
javax.ws.rs.ProcessingException: Determining baseURL failed for hostURL='https://localhost:8388/': javax.ws.rs.ProcessingException: javax.net.ssl.SSLException: readHandshakeRecord
	at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.determineBaseUrl(CloudStoreRestClient.java:130)
	at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.getBaseUrl(CloudStoreRestClient.java:75)
	at co.codewizards.cloudstore.rest.client.request.AbstractRequest.getBaseURL(AbstractRequest.java:146)
	at co.codewizards.cloudstore.rest.client.request.AbstractRequest.createWebTarget(AbstractRequest.java:115)
	at co.codewizards.cloudstore.rest.client.request.TestRequest.execute(TestRequest.java:27)
	at co.codewizards.cloudstore.rest.client.request.TestRequest.execute(TestRequest.java:6)
	at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.execute(CloudStoreRestClient.java:175)
	at co.codewizards.cloudstore.test.CertificateHandlingAndTestServiceLRT.almostTimedOut_5min(CertificateHandlingAndTestServiceLRT.java:89)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
	at mockit.integration.junit4.internal.JUnit4TestRunnerDecorator.executeTestMethod(JUnit4TestRunnerDecorator.java:156)
	at mockit.integration.junit4.internal.JUnit4TestRunnerDecorator.invokeExplosively(JUnit4TestRunnerDecorator.java:65)
	at mockit.integration.junit4.internal.MockFrameworkMethod.invokeExplosively(MockFrameworkMethod.java:37)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
	at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273)
	at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238)
	at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:377)
	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:138)
	at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:465)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:451)
Caused by: javax.ws.rs.ProcessingException: javax.net.ssl.SSLException: readHandshakeRecord
	at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:260)
	at org.glassfish.jersey.client.ClientRuntime.invoke(ClientRuntime.java:254)
	at org.glassfish.jersey.client.JerseyInvocation.lambda$invoke$1(JerseyInvocation.java:750)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:292)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:274)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:205)
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:390)
	at org.glassfish.jersey.client.JerseyInvocation.invoke(JerseyInvocation.java:748)
	at org.glassfish.jersey.client.JerseyInvocation$Builder.method(JerseyInvocation.java:404)
	at org.glassfish.jersey.client.JerseyInvocation$Builder.get(JerseyInvocation.java:300)
	at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.determineBaseUrl(CloudStoreRestClient.java:118)
	... 40 common frames omitted
Caused by: javax.net.ssl.SSLException: readHandshakeRecord
	at java.base/sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1071)
	at java.base/sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:402)
	at java.base/sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:567)
	at java.base/sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:185)
	at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1587)
	at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1515)
	at java.base/java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:527)
	at java.base/sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:334)
	at org.glassfish.jersey.client.internal.HttpUrlConnector._apply(HttpUrlConnector.java:366)
	at org.glassfish.jersey.client.internal.HttpUrlConnector.apply(HttpUrlConnector.java:258)
	... 50 common frames omitted
	Suppressed: java.net.SocketException: Broken pipe (Write failed)
		at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
		at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
		at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
		at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
		at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
		at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
		at java.base/sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:405)
		... 58 common frames omitted
Caused by: java.net.SocketException: Broken pipe (Write failed)
	at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
	at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
	at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
	at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeChangeCipherSpec(SSLSocketOutputRecord.java:221)
	at java.base/sun.security.ssl.OutputRecord.changeWriteCiphers(OutputRecord.java:162)
	at java.base/sun.security.ssl.ChangeCipherSpec$T10ChangeCipherSpecProducer.produce(ChangeCipherSpec.java:118)
	at java.base/sun.security.ssl.Finished$T12FinishedProducer.onProduceFinished(Finished.java:395)
	at java.base/sun.security.ssl.Finished$T12FinishedProducer.produce(Finished.java:379)
	at java.base/sun.security.ssl.SSLHandshake.produce(SSLHandshake.java:436)
	at java.base/sun.security.ssl.ServerHelloDone$ServerHelloDoneConsumer.consume(ServerHelloDone.java:182)
	at java.base/sun.security.ssl.SSLHandshake.consume(SSLHandshake.java:392)
	at java.base/sun.security.ssl.HandshakeContext.dispatch(HandshakeContext.java:443)
	at java.base/sun.security.ssl.HandshakeContext.dispatch(HandshakeContext.java:421)
	at java.base/sun.security.ssl.TransportContext.dispatch(TransportContext.java:177)
	at java.base/sun.security.ssl.SSLTransport.decode(SSLTransport.java:164)
	at java.base/sun.security.ssl.SSLSocketImpl.decode(SSLSocketImpl.java:1151)
	at java.base/sun.security.ssl.SSLSocketImpl.readHandshakeRecord(SSLSocketImpl.java:1062)
	... 59 common frames omitted
[WARNING] Tests run: 4, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 430.809 s - in co.codewizards.cloudstore.test.CertificateHandlingAndTestServiceLRT
16:08:53.770 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@7d0358e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
16:08:53.772 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:53.798 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@2443ac0d{/,null,UNAVAILABLE}
16:08:53.808 [Thread-3] INFO  c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/jvm/dfVAANVfpUM-r2XwUfy0Rg/.cloudstore/localServerRunning.deleteToStop
16:08:53.820 [Thread-3] INFO  o.e.j.s.AbstractConnector - Stopped ServerConnector@528433ef{SSL,[ssl, http/1.1]}{0.0.0.0:8388}
16:08:53.821 [Thread-3] INFO  o.e.j.s.session - node0 Stopped scavenging
16:08:53.834 [Thread-3] INFO  o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@4f413a44{/,null,UNAVAILABLE}
[INFO] 
[INFO] Results:
[INFO] 
[WARNING] Tests run: 52, Failures: 0, Errors: 0, Skipped: 1
[INFO] 
[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-source-plugin:3.2.1:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.test ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-failsafe-plugin:3.0.0-M4:verify (default) @ co.codewizards.cloudstore.test ---
[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ co.codewizards.cloudstore.test ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.test ---
[INFO] Downloading from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml
[INFO] Downloaded from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml (1.0 kB at 60 kB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3.jar
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3.jar (7.3 kB at 7.3 MB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3.pom
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3.pom (5.3 kB at 5.3 MB/s)
[INFO] Downloading from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml
[INFO] Downloaded from cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml (315 B at 158 kB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml (1.0 kB at 1.0 MB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml (315 B at 315 kB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3-sources.jar
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/co.codewizards.cloudstore.test-1.0.1-20200419.140855-3-sources.jar (5.0 kB at 2.5 MB/s)
[INFO] Uploading to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded to cloudstore-snapshot-repo: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/1.0.1-SNAPSHOT/maven-metadata.xml (1.0 kB at 1.0 MB/s)
[INFO] 
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.test ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.test
[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.test/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore_java_11/site/co.codewizards.cloudstore.test
[INFO] 
[INFO] >>> maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) > generate-sources @ co.codewizards.cloudstore.test >>>
[WARNING] Failed to getClass for org.apache.maven.plugins.javadoc.JavadocReport
[JENKINS] Archiving  javadoc
[INFO] 
[INFO] <<< maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) < generate-sources @ co.codewizards.cloudstore.test <<<
[INFO] 
[INFO] 
[INFO] --- maven-javadoc-plugin:3.1.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.test ---
[INFO] Skipping javadoc generation
[JENKINS] Archiving  javadoc
[INFO] 
[INFO] --- maven-resources-plugin:2.6:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.test ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/javadoc.unfiltered
[INFO] 
[INFO] --- maven-resources-plugin:2.6:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.test ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/apidocs
[INFO] 
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.test ---
file:/tmp/cloudstore-junk/ - Session: Opened  
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_11/co.codewizards.cloudstore.test/target/site
[INFO]    >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.test
[INFO]    >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.test
file:/tmp/cloudstore-junk/ - Session: Disconnecting  
file:/tmp/cloudstore-junk/ - Session: Disconnected