|  Console Output
Skipping 701 KB.. Full Logcal sync...: 58.00%
10:10:05.474 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:05.493 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:10:05.519 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.41%
10:10:05.536 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:10:05.580 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
10:10:05.603 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:05.624 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:05.624 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: e095bfde-bd46-4e25-889d-63d170f24a02
10:10:05.628 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:05.629 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e095bfde-bd46-4e25-889d-63d170f24a02 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2' toID=03db62e9-4f66-41ce-8c64-aacc873d4e64 to='target/repo/jz6otser-t4tx8-local', fromRepoLocalSync=true
10:10:05.629 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 03db62e9-4f66-41ce-8c64-aacc873d4e64 ('target/repo/jz6otser-t4tx8-local')
10:10:05.668 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:05.675 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:05.676 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:05.678 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:05.679 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:05.685 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:05.690 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:05.698 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:05.701 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:05.706 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:05.714 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:05.735 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:10:05.785 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:05.809 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:05.825 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:10:05.831 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:10:05.831 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:10:05.831 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:10:05.841 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fc9456a9ce6ef552dc3c0a27a0e5c490c6b57827' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:10:05.879 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:05.881 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:10:05.881 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fc9456a9ce6ef552dc3c0a27a0e5c490c6b57827' toSha1='null'
10:10:05.881 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:10:05.881 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:10:05.882 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:10:05.887 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 21488 bytes in 6 ms. path='/1/a'
10:10:05.891 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:05.923 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:05.930 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:10:05.939 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5011370040176809cb7f672ca2566f93d92ff2db' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:10:05.951 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:05.952 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:10:05.952 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5011370040176809cb7f672ca2566f93d92ff2db' toSha1='null'
10:10:05.952 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:10:05.952 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:10:05.954 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:10:05.961 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150627 bytes in 9 ms. path='/1/b'
10:10:05.965 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:05.980 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:05.984 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:10:05.992 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='25a663e74c820f5d981284b2d7a62e9440416ace' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:10:06.005 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:06.007 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:10:06.007 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='25a663e74c820f5d981284b2d7a62e9440416ace' toSha1='null'
10:10:06.008 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:10:06.008 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:10:06.017 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:10:06.027 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 468441 bytes in 19 ms. path='/a'
10:10:06.030 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:06.062 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:06.072 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:10:06.081 [closeDeferredTimer-3f455e80-3] INFO c.c.c.l.LocalRepoManagerImpl - [3f455e80]_close: Shutting down real LocalRepoManager.
10:10:06.083 [closeDeferredTimer-46d8824b-3] INFO c.c.c.l.LocalRepoManagerImpl - [46d8824b]_close: Shutting down real LocalRepoManager.
10:10:06.865 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=2
10:10:06.923 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 transaction.localRevision=19 remoteFromLocalRevision=2
10:10:06.928 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=03db62e9-4f66-41ce-8c64-aacc873d4e64 from='target/repo/jz6otser-t4tx8-local' toID=e095bfde-bd46-4e25-889d-63d170f24a02 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2'
10:10:06.972 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:10:06.994 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:06.994 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:07.010 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 localRepositoryRevisionSynced=19
10:10:07.057 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 transaction.localRevision=5 remoteFromLocalRevision=19
10:10:07.069 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e095bfde-bd46-4e25-889d-63d170f24a02 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2' toID=03db62e9-4f66-41ce-8c64-aacc873d4e64 to='target/repo/jz6otser-t4tx8-local', fromRepoLocalSync=false
10:10:07.090 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:10:07.096 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:07.096 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:07.105 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=5
10:10:07.174 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 transaction.localRevision=22 remoteFromLocalRevision=5
10:10:07.185 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:07.207 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5accd405]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:07.207 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5accd405]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:07.208 [main] INFO c.c.c.l.LocalRepoManagerImpl - [20582f90]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:07.208 [main] INFO c.c.c.l.LocalRepoManagerImpl - [20582f90]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:07.235 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:07.236 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:07.237 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:10:07.240 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:07.244 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:10:07.276 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:10:07.279 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:10:07.302 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:10:07.335 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:07.370 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:07.375 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e095bfde-bd46-4e25-889d-63d170f24a02 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2' toID=03db62e9-4f66-41ce-8c64-aacc873d4e64 to='target/repo/jz6otser-t4tx8-local', fromRepoLocalSync=true
10:10:07.376 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 03db62e9-4f66-41ce-8c64-aacc873d4e64 ('target/repo/jz6otser-t4tx8-local')
10:10:07.385 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:07.392 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:10:07.403 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:07.410 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:07.411 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:07.412 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:07.415 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:07.419 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:07.432 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:07.436 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:07.441 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:10:07.449 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:07.473 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:10:07.489 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:07.503 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:10:07.504 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
10:10:07.522 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:10:07.523 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:10:07.523 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
10:10:07.532 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='025a6d292cb15fb69681d525dfa32df970d50731' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
10:10:07.560 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:07.580 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
10:10:07.580 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='025a6d292cb15fb69681d525dfa32df970d50731' toSha1='null'
10:10:07.580 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
10:10:07.580 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
10:10:07.587 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
10:10:07.598 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 379900 bytes in 18 ms. path='/1/5/aaa'
10:10:07.603 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:10:07.675 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:07.739 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
10:10:07.750 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='be30b66e84a8ede53baa6ac7dfc6db255031208f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
10:10:07.824 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:07.855 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
10:10:07.855 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='be30b66e84a8ede53baa6ac7dfc6db255031208f' toSha1='null'
10:10:07.855 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
10:10:07.855 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
10:10:07.856 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
10:10:07.865 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 20254 bytes in 10 ms. path='/1/5/bbb'
10:10:07.871 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:10:07.937 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e095bfde-bd46-4e25-889d-63d170f24a02"] on repo=03db62e9-4f66-41ce-8c64-aacc873d4e64
10:10:07.947 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:10:07.951 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=8
10:10:07.957 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 transaction.localRevision=35 remoteFromLocalRevision=8
10:10:07.964 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=03db62e9-4f66-41ce-8c64-aacc873d4e64 from='target/repo/jz6otser-t4tx8-local' toID=e095bfde-bd46-4e25-889d-63d170f24a02 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2'
10:10:08.039 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
10:10:08.042 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:08.042 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:08.047 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 localRepositoryRevisionSynced=35
10:10:08.056 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 transaction.localRevision=11 remoteFromLocalRevision=35
10:10:08.059 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e095bfde-bd46-4e25-889d-63d170f24a02 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6otser-bqul8-remote/2' toID=03db62e9-4f66-41ce-8c64-aacc873d4e64 to='target/repo/jz6otser-t4tx8-local', fromRepoLocalSync=false
10:10:08.072 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:10:08.080 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:08.080 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:08.083 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 remoteRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 localRepositoryRevisionSynced=11
10:10:08.120 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=03db62e9-4f66-41ce-8c64-aacc873d4e64 remoteRepositoryId=e095bfde-bd46-4e25-889d-63d170f24a02 transaction.localRevision=38 remoteFromLocalRevision=11
10:10:08.129 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:08.129 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5accd405]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:08.145 [main] INFO c.c.c.l.LocalRepoManagerImpl - [20582f90]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:08.169 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tk-satci-local'
10:10:08.175 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tk-satci-local' locked successfully.
10:10:11.114 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]initPersistenceManagerFactory: repositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:11.116 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1387 ms.
10:10:11.132 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote'
10:10:11.137 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote' locked successfully.
10:10:14.442 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]initPersistenceManagerFactory: repositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a
10:10:14.444 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1983 ms.
10:10:14.664 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:14.669 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:14.679 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:10:14.717 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:14.730 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:10:14.767 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.02%
10:10:14.778 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:10:14.804 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.73%
10:10:14.829 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:14.849 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:14.849 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: afb9859b-1721-4159-9b9c-8edc68d9706a
10:10:14.853 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:14.854 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=afb9859b-1721-4159-9b9c-8edc68d9706a from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote' toID=10a55223-6ab4-4826-a6f4-da6864cae4cc to='target/repo/jz6ou0tk-satci-local', fromRepoLocalSync=true
10:10:14.854 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 10a55223-6ab4-4826-a6f4-da6864cae4cc ('target/repo/jz6ou0tk-satci-local')
10:10:14.877 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:14.883 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:14.886 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:14.887 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:14.889 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:14.894 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:14.897 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:14.903 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:14.907 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:14.911 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:14.917 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:14.937 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:10:14.972 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:14.997 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:15.009 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:10:15.009 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:10:15.016 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:10:15.026 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:10:15.038 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:10:15.039 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:10:15.039 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:10:15.046 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ff4dd581d1028dcb6e472a289ca4eb600204266f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:10:15.081 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.083 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:10:15.083 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ff4dd581d1028dcb6e472a289ca4eb600204266f' toSha1='null'
10:10:15.083 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:10:15.083 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:10:15.084 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:10:15.090 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 125090 bytes in 7 ms. path='/1/a'
10:10:15.094 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:15.132 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.140 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:10:15.151 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9e16aa8cecc4a0ae8b7a2320cb780a5f9c5e3c11' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:10:15.165 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.167 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:10:15.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9e16aa8cecc4a0ae8b7a2320cb780a5f9c5e3c11' toSha1='null'
10:10:15.167 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:10:15.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:10:15.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:10:15.171 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 10252 bytes in 4 ms. path='/1/b'
10:10:15.175 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:15.184 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.189 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:10:15.196 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='be53299ade3322c399b1561c9fde183d6adf40e2' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:10:15.208 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.209 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:10:15.209 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='be53299ade3322c399b1561c9fde183d6adf40e2' toSha1='null'
10:10:15.209 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:10:15.209 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:10:15.213 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:10:15.223 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 388107 bytes in 14 ms. path='/1/c'
10:10:15.227 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:15.250 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.254 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:10:15.264 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='14dc832a2e4d732e92a1abaf279d5774008e2e78' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:10:15.277 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.279 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:10:15.279 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='14dc832a2e4d732e92a1abaf279d5774008e2e78' toSha1='null'
10:10:15.279 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:10:15.279 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:10:15.289 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:10:15.303 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 781575 bytes in 24 ms. path='/2/1/a'
10:10:15.308 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:15.346 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.350 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:10:15.363 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ad69febf9f48fdca816ee02e7a458b23aff8f886' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:10:15.376 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.378 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:10:15.378 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ad69febf9f48fdca816ee02e7a458b23aff8f886' toSha1='null'
10:10:15.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:10:15.378 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:10:15.380 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:10:15.390 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150216 bytes in 12 ms. path='/2/1/b'
10:10:15.396 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:15.413 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.416 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:10:15.424 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7dfbed8b255d303b87ece33c98989c25fc7d9c3d' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:10:15.435 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.437 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:10:15.438 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7dfbed8b255d303b87ece33c98989c25fc7d9c3d' toSha1='null'
10:10:15.438 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:10:15.438 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:10:15.439 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:10:15.444 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 90052 bytes in 6 ms. path='/2/a'
10:10:15.448 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:15.460 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.463 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:10:15.471 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='87458eacd43c20cac488203046b34ec3aeb3930d' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:10:15.486 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.487 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:10:15.487 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='87458eacd43c20cac488203046b34ec3aeb3930d' toSha1='null'
10:10:15.487 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:10:15.487 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:10:15.490 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:10:15.497 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 187600 bytes in 10 ms. path='/3/a'
10:10:15.500 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:15.516 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.519 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:10:15.526 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5cb251e1773040d08132e7fd17bdfa493660d6e0' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:10:15.537 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.539 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:10:15.539 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5cb251e1773040d08132e7fd17bdfa493660d6e0' toSha1='null'
10:10:15.539 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:10:15.539 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:10:15.539 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:10:15.549 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 232696 bytes in 10 ms. path='/3/b'
10:10:15.553 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:15.572 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.575 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:10:15.585 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8e47a2da9362fb56b3ef83cb52188fec081a4b2e' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:10:15.598 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.599 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:10:15.599 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8e47a2da9362fb56b3ef83cb52188fec081a4b2e' toSha1='null'
10:10:15.599 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:10:15.599 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:10:15.602 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:10:15.608 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 187920 bytes in 9 ms. path='/3/c'
10:10:15.612 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:15.627 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.630 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:10:15.637 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0e75b555f98c0e1f2a3ba6824108b9bb9e7d0a04' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:10:15.653 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.654 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:10:15.655 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0e75b555f98c0e1f2a3ba6824108b9bb9e7d0a04' toSha1='null'
10:10:15.655 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:10:15.655 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:10:15.658 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:10:15.671 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 320722 bytes in 16 ms. path='/3/d'
10:10:15.675 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:10:15.696 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:15.700 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:10:15.718 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=2
10:10:15.725 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a transaction.localRevision=57 remoteFromLocalRevision=2
10:10:15.730 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=10a55223-6ab4-4826-a6f4-da6864cae4cc from='target/repo/jz6ou0tk-satci-local' toID=afb9859b-1721-4159-9b9c-8edc68d9706a to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote'
10:10:15.750 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:10:15.759 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:15.760 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:15.770 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a localRepositoryRevisionSynced=57
10:10:15.778 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc transaction.localRevision=5 remoteFromLocalRevision=57
10:10:15.779 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=afb9859b-1721-4159-9b9c-8edc68d9706a from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote' toID=10a55223-6ab4-4826-a6f4-da6864cae4cc to='target/repo/jz6ou0tk-satci-local', fromRepoLocalSync=false
10:10:15.791 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:10:15.793 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:15.793 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:15.796 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=5
10:10:15.800 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a transaction.localRevision=60 remoteFromLocalRevision=5
10:10:15.802 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:15.816 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:15.817 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:15.817 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:15.818 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:15.887 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:15.888 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:15.890 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:10:15.893 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:15.897 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:10:15.938 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:10:15.942 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:10:15.959 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:10:15.975 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:15.996 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:15.998 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=afb9859b-1721-4159-9b9c-8edc68d9706a from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote' toID=10a55223-6ab4-4826-a6f4-da6864cae4cc to='target/repo/jz6ou0tk-satci-local', fromRepoLocalSync=true
10:10:15.999 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 10a55223-6ab4-4826-a6f4-da6864cae4cc ('target/repo/jz6ou0tk-satci-local')
10:10:16.013 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:16.036 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:10:16.056 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:10:16.071 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:16.074 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:16.078 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:16.083 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:16.086 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:16.100 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:16.104 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:16.110 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:10:16.118 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:16.126 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:10:16.140 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
10:10:16.141 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:10:16.157 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
10:10:16.168 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:10:16.177 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
10:10:16.179 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
10:10:16.179 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
10:10:16.179 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
10:10:16.192 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d891de4b7befa31961863fd0ae4ef8188c47f1e7' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
10:10:16.210 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.212 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
10:10:16.212 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d891de4b7befa31961863fd0ae4ef8188c47f1e7' toSha1='null'
10:10:16.212 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
10:10:16.212 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/5/aaa' offset=0
10:10:16.218 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
10:10:16.232 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 477959 bytes in 20 ms. path='/2/1/5/aaa'
10:10:16.238 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:10:16.266 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.269 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
10:10:16.283 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3f7250346e06c7270326e034d1a697d45cbb1863' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
10:10:16.297 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.298 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
10:10:16.298 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3f7250346e06c7270326e034d1a697d45cbb1863' toSha1='null'
10:10:16.298 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
10:10:16.298 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/5/bbb' offset=0
10:10:16.301 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
10:10:16.311 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 237348 bytes in 13 ms. path='/2/1/5/bbb'
10:10:16.316 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:10:16.332 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.335 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
10:10:16.342 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ba60859ebceaa9bf5fb5dc2801194cc4aa4e7162' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
10:10:16.354 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.355 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
10:10:16.355 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ba60859ebceaa9bf5fb5dc2801194cc4aa4e7162' toSha1='null'
10:10:16.355 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
10:10:16.355 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
10:10:16.359 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
10:10:16.368 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 364080 bytes in 13 ms. path='/3/e'
10:10:16.372 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
10:10:16.394 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="afb9859b-1721-4159-9b9c-8edc68d9706a"] on repo=10a55223-6ab4-4826-a6f4-da6864cae4cc
10:10:16.400 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=8
10:10:16.405 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a transaction.localRevision=79 remoteFromLocalRevision=8
10:10:16.408 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=10a55223-6ab4-4826-a6f4-da6864cae4cc from='target/repo/jz6ou0tk-satci-local' toID=afb9859b-1721-4159-9b9c-8edc68d9706a to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote'
10:10:16.419 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
10:10:16.421 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:10:16.421 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:16.421 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:16.424 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a localRepositoryRevisionSynced=79
10:10:16.428 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc transaction.localRevision=11 remoteFromLocalRevision=79
10:10:16.429 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=afb9859b-1721-4159-9b9c-8edc68d9706a from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou0tl-5s59v-remote' toID=10a55223-6ab4-4826-a6f4-da6864cae4cc to='target/repo/jz6ou0tk-satci-local', fromRepoLocalSync=false
10:10:16.436 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:10:16.439 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:16.439 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:16.443 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a remoteRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc localRepositoryRevisionSynced=11
10:10:16.446 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=10a55223-6ab4-4826-a6f4-da6864cae4cc remoteRepositoryId=afb9859b-1721-4159-9b9c-8edc68d9706a transaction.localRevision=82 remoteFromLocalRevision=11
10:10:16.447 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:16.447 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:16.465 [main] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:16.529 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-o71my-local'
10:10:16.535 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-o71my-local' locked successfully.
10:10:17.249 [closeDeferredTimer-765df0f1-1] INFO c.c.c.l.LocalRepoManagerImpl - [765df0f1]_close: Shutting down real LocalRepoManager.
10:10:17.249 [closeDeferredTimer-787d2b28-1] INFO c.c.c.l.LocalRepoManagerImpl - [787d2b28]_close: Shutting down real LocalRepoManager.
10:10:20.596 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]initPersistenceManagerFactory: repositoryId=04951527-7983-4400-9533-cfd8c79a3968
10:10:20.598 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1949 ms.
10:10:20.620 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote'
10:10:20.625 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote' locked successfully.
10:10:24.857 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]initPersistenceManagerFactory: repositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f
10:10:24.863 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2672 ms.
10:10:24.964 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:24.972 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:24.983 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:10:25.040 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:25.061 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:10:25.086 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.52%
10:10:25.100 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:10:25.130 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.68%
10:10:25.161 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:25.182 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 04951527-7983-4400-9533-cfd8c79a3968
10:10:25.183 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: b2af4a16-242c-4fa4-adf1-9e9ef27fde6f
10:10:25.186 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:25.187 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote' toID=04951527-7983-4400-9533-cfd8c79a3968 to='target/repo/jz6ou79s-o71my-local', fromRepoLocalSync=true
10:10:25.187 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 04951527-7983-4400-9533-cfd8c79a3968 ('target/repo/jz6ou79s-o71my-local')
10:10:25.220 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:25.228 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:25.229 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:25.230 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:25.232 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:25.236 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:25.240 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:25.245 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:25.248 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:25.251 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:25.257 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:25.283 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:10:25.314 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:25.336 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:25.348 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:10:25.348 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:10:25.356 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:10:25.365 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:10:25.377 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:10:25.378 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:10:25.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:10:25.385 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e1d9a16c0ad50fdd71cf5449d855d543dcdfe228' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:10:25.418 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.419 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:10:25.419 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e1d9a16c0ad50fdd71cf5449d855d543dcdfe228' toSha1='null'
10:10:25.419 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:10:25.419 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:10:25.425 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:10:25.436 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 472719 bytes in 17 ms. path='/1/a'
10:10:25.441 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:25.486 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.494 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:10:25.504 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='08d7d985d842d7ec9022416581350b92f85e7f70' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:10:25.516 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.517 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:10:25.518 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='08d7d985d842d7ec9022416581350b92f85e7f70' toSha1='null'
10:10:25.518 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:10:25.518 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:10:25.520 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:10:25.528 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 245768 bytes in 10 ms. path='/1/b'
10:10:25.531 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:25.548 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.553 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:10:25.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d6f745f01910d403174e4ef52f7efa6a8ac08046' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:10:25.574 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.576 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:10:25.576 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d6f745f01910d403174e4ef52f7efa6a8ac08046' toSha1='null'
10:10:25.576 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:10:25.576 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:10:25.581 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:10:25.591 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 479710 bytes in 15 ms. path='/1/c'
10:10:25.595 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:25.624 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.629 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:10:25.639 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e8111f50efe1db113d89b421ba2791462b8ed70f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:10:25.652 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.653 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:10:25.653 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e8111f50efe1db113d89b421ba2791462b8ed70f' toSha1='null'
10:10:25.653 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:10:25.653 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:10:25.656 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:10:25.663 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 204540 bytes in 10 ms. path='/2/1/a'
10:10:25.667 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:25.682 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.686 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:10:25.694 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b812724d9fcbf187ae729a5b669fa3ad9b286911' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:10:25.708 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.709 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:10:25.709 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b812724d9fcbf187ae729a5b669fa3ad9b286911' toSha1='null'
10:10:25.709 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:10:25.709 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:10:25.711 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:10:25.718 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 152280 bytes in 9 ms. path='/2/1/b'
10:10:25.722 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:25.737 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.742 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:10:25.757 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d4e4b9d73b3760eed0d8cca4e03f2687008d0c28' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:10:25.768 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.769 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:10:25.769 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d4e4b9d73b3760eed0d8cca4e03f2687008d0c28' toSha1='null'
10:10:25.769 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:10:25.769 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:10:25.774 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:10:25.782 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 344750 bytes in 13 ms. path='/2/a'
10:10:25.787 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:25.807 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.811 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:10:25.821 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6bedbdb0c12a136694f9da96fe45e55415811ea7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:10:25.834 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.835 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:10:25.835 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6bedbdb0c12a136694f9da96fe45e55415811ea7' toSha1='null'
10:10:25.835 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:10:25.835 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:10:25.837 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:10:25.844 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 159495 bytes in 9 ms. path='/3/a'
10:10:25.849 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:25.863 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.866 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:10:25.874 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fb96e4468fc7ec4e31d3ce146495723f455b6189' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:10:25.887 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.889 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:10:25.889 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fb96e4468fc7ec4e31d3ce146495723f455b6189' toSha1='null'
10:10:25.889 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:10:25.889 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:10:25.891 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:10:25.897 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 136656 bytes in 8 ms. path='/3/b'
10:10:25.901 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:25.916 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.919 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:10:25.929 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e0ee1cdcc6f2b7a2629ce3347d931e9b116ccb96' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:10:25.943 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.944 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:10:25.944 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e0ee1cdcc6f2b7a2629ce3347d931e9b116ccb96' toSha1='null'
10:10:25.944 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:10:25.944 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:10:25.947 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:10:25.954 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 215820 bytes in 10 ms. path='/3/c'
10:10:25.957 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:25.973 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:25.976 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:10:25.984 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='da522124ef2e5c47fa7dc8540eb1dc570cd99694' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:10:25.999 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:26.000 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:10:26.000 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='da522124ef2e5c47fa7dc8540eb1dc570cd99694' toSha1='null'
10:10:26.000 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:10:26.000 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:10:26.008 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:10:26.026 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 724360 bytes in 26 ms. path='/3/d'
10:10:26.030 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:10:26.066 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b2af4a16-242c-4fa4-adf1-9e9ef27fde6f"] on repo=04951527-7983-4400-9533-cfd8c79a3968
10:10:26.072 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:10:26.091 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=2
10:10:26.106 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f transaction.localRevision=57 remoteFromLocalRevision=2
10:10:26.114 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=04951527-7983-4400-9533-cfd8c79a3968 from='target/repo/jz6ou79s-o71my-local' toID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote'
10:10:26.138 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:10:26.148 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:26.148 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:26.158 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f localRepositoryRevisionSynced=57
10:10:26.165 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 transaction.localRevision=5 remoteFromLocalRevision=57
10:10:26.171 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote' toID=04951527-7983-4400-9533-cfd8c79a3968 to='target/repo/jz6ou79s-o71my-local', fromRepoLocalSync=false
10:10:26.189 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:10:26.193 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:26.193 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:26.198 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=5
10:10:26.205 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f transaction.localRevision=60 remoteFromLocalRevision=5
10:10:26.207 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:26.225 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:26.225 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:26.226 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:26.226 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:26.281 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:26.282 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:26.283 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:10:26.287 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:26.291 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:10:26.337 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:10:26.344 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:10:26.348 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:10:26.361 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:26.384 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:26.386 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote' toID=04951527-7983-4400-9533-cfd8c79a3968 to='target/repo/jz6ou79s-o71my-local', fromRepoLocalSync=true
10:10:26.386 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 04951527-7983-4400-9533-cfd8c79a3968 ('target/repo/jz6ou79s-o71my-local')
10:10:26.391 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:26.405 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:10:26.423 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:10:26.440 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:26.441 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:26.442 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:26.446 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:26.450 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:26.456 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:26.461 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:26.465 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:26.472 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:26.482 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:10:26.503 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
10:10:26.503 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:10:26.518 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
10:10:26.519 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:10:26.554 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
10:10:26.554 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:10:26.572 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=8
10:10:26.581 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f transaction.localRevision=63 remoteFromLocalRevision=8
10:10:26.588 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=04951527-7983-4400-9533-cfd8c79a3968 from='target/repo/jz6ou79s-o71my-local' toID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote'
10:10:26.602 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:10:26.612 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:10:26.612 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:10:26.612 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:10:26.617 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:10:26.617 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:26.634 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f localRepositoryRevisionSynced=63
10:10:26.638 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 transaction.localRevision=11 remoteFromLocalRevision=63
10:10:26.647 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ou79s-egpnk-remote' toID=04951527-7983-4400-9533-cfd8c79a3968 to='target/repo/jz6ou79s-o71my-local', fromRepoLocalSync=false
10:10:26.659 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:10:26.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:26.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:26.667 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f remoteRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 localRepositoryRevisionSynced=11
10:10:26.675 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=04951527-7983-4400-9533-cfd8c79a3968 remoteRepositoryId=b2af4a16-242c-4fa4-adf1-9e9ef27fde6f transaction.localRevision=66 remoteFromLocalRevision=11
10:10:26.676 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:26.676 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3d13b2a3]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:26.694 [main] INFO c.c.c.l.LocalRepoManagerImpl - [773d2ea8]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:26.749 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-qfoh2-local'
10:10:26.757 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-qfoh2-local' locked successfully.
10:10:28.132 [closeDeferredTimer-5accd405-2] INFO c.c.c.l.LocalRepoManagerImpl - [5accd405]_close: Shutting down real LocalRepoManager.
10:10:28.441 [closeDeferredTimer-20582f90-2] INFO c.c.c.l.LocalRepoManagerImpl - [20582f90]_close: Shutting down real LocalRepoManager.
10:10:31.518 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]initPersistenceManagerFactory: repositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:31.521 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3572 ms.
10:10:31.542 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote'
10:10:31.548 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote' locked successfully.
10:10:35.621 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]initPersistenceManagerFactory: repositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8
10:10:35.627 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2403 ms.
10:10:35.725 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:35.732 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:35.743 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:10:35.791 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:35.806 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:10:35.841 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.45%
10:10:35.854 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:10:35.870 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
10:10:35.908 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:35.930 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:35.930 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: b3253c17-9dc8-4707-959b-2ab740d8ceb8
10:10:35.935 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:35.936 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2' toID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 to='target/repo/jz6ouf5m-qfoh2-local', fromRepoLocalSync=true
10:10:35.936 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 7cda4ab7-07e8-4c40-b337-f9d03ea9b777 ('target/repo/jz6ouf5m-qfoh2-local')
10:10:35.964 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:35.974 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:35.980 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:35.981 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:35.984 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:35.991 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:35.995 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:36.002 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:36.005 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:36.009 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:36.016 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:36.048 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:10:36.100 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:36.122 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:36.134 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:10:36.146 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:10:36.146 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:10:36.146 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:10:36.155 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e030004d68a2a85a2ee1297d646ecf6ce9c45266' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:10:36.199 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.202 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:10:36.203 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e030004d68a2a85a2ee1297d646ecf6ce9c45266' toSha1='null'
10:10:36.203 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:10:36.203 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:10:36.219 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:10:36.232 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 639496 bytes in 29 ms. path='/1/a'
10:10:36.236 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:36.290 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.301 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:10:36.308 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='039cb821ebfc16b4aa5393f969cd3fb1891e6e1c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:10:36.320 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.321 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:10:36.321 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='039cb821ebfc16b4aa5393f969cd3fb1891e6e1c' toSha1='null'
10:10:36.321 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:10:36.322 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:10:36.324 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:10:36.330 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 173666 bytes in 8 ms. path='/1/b'
10:10:36.334 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:36.350 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.353 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:10:36.359 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='eb981baa4932a63d7a0d7db19dfc63e515f07a5d' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:10:36.371 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.372 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:10:36.372 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='eb981baa4932a63d7a0d7db19dfc63e515f07a5d' toSha1='null'
10:10:36.372 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:10:36.372 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:10:36.375 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:10:36.381 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 223800 bytes in 9 ms. path='/a'
10:10:36.384 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:36.402 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b3253c17-9dc8-4707-959b-2ab740d8ceb8"] on repo=7cda4ab7-07e8-4c40-b337-f9d03ea9b777
10:10:36.405 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:10:36.423 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=2
10:10:36.436 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 transaction.localRevision=19 remoteFromLocalRevision=2
10:10:36.443 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 from='target/repo/jz6ouf5m-qfoh2-local' toID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2'
10:10:36.448 [closeDeferredTimer-2fdd11ac-2] INFO c.c.c.l.LocalRepoManagerImpl - [2fdd11ac]_close: Shutting down real LocalRepoManager.
10:10:36.743 [closeDeferredTimer-600d93ad-2] INFO c.c.c.l.LocalRepoManagerImpl - [600d93ad]_close: Shutting down real LocalRepoManager.
10:10:37.082 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:10:37.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:37.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:37.137 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 localRepositoryRevisionSynced=19
10:10:37.149 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 transaction.localRevision=5 remoteFromLocalRevision=19
10:10:37.227 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2' toID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 to='target/repo/jz6ouf5m-qfoh2-local', fromRepoLocalSync=false
10:10:37.250 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:10:37.260 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:37.260 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:37.264 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=5
10:10:37.305 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 transaction.localRevision=22 remoteFromLocalRevision=5
10:10:37.310 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:37.327 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:37.327 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:37.328 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:37.328 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:37.356 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:37.358 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:37.359 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:10:37.362 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:37.366 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:10:37.418 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:10:37.421 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:10:37.425 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:10:37.468 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:37.492 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:37.493 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2' toID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 to='target/repo/jz6ouf5m-qfoh2-local', fromRepoLocalSync=true
10:10:37.493 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 7cda4ab7-07e8-4c40-b337-f9d03ea9b777 ('target/repo/jz6ouf5m-qfoh2-local')
10:10:37.496 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:37.503 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:10:37.515 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:37.523 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:37.524 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:37.525 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:37.528 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:37.536 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:37.541 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:37.544 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:37.548 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:37.556 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:37.594 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:10:37.616 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
10:10:37.617 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:37.680 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:10:37.835 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
10:10:37.836 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
10:10:37.866 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=8
10:10:37.877 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 transaction.localRevision=25 remoteFromLocalRevision=8
10:10:37.900 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 from='target/repo/jz6ouf5m-qfoh2-local' toID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2'
10:10:37.918 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
10:10:37.928 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:10:37.929 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:10:37.936 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:10:37.936 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:37.953 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 localRepositoryRevisionSynced=25
10:10:37.976 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 transaction.localRevision=11 remoteFromLocalRevision=25
10:10:38.000 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b3253c17-9dc8-4707-959b-2ab740d8ceb8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouf5m-cleer-remote/2' toID=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 to='target/repo/jz6ouf5m-qfoh2-local', fromRepoLocalSync=false
10:10:38.015 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:10:38.021 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:38.021 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:38.025 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 remoteRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 localRepositoryRevisionSynced=11
10:10:38.110 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7cda4ab7-07e8-4c40-b337-f9d03ea9b777 remoteRepositoryId=b3253c17-9dc8-4707-959b-2ab740d8ceb8 transaction.localRevision=28 remoteFromLocalRevision=11
10:10:38.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:38.114 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6f41fbbc]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:38.132 [main] INFO c.c.c.l.LocalRepoManagerImpl - [30167ae9]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:38.144 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny7-dgxnp-local'
10:10:38.149 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny7-dgxnp-local' locked successfully.
10:10:41.344 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]initPersistenceManagerFactory: repositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:41.346 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1312 ms.
10:10:41.364 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote'
10:10:41.369 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote' locked successfully.
10:10:44.861 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]initPersistenceManagerFactory: repositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f
10:10:44.868 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2115 ms.
10:10:44.945 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:44.949 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:44.957 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:10:44.994 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:10:45.005 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:10:45.026 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.64%
10:10:45.037 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:10:45.060 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
10:10:45.090 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:45.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: cc1a37e2-f719-44a0-baa6-b34c04db0f2f
10:10:45.118 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:45.118 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote' toID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 to='target/repo/jz6ouny7-dgxnp-local', fromRepoLocalSync=true
10:10:45.119 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 ('target/repo/jz6ouny7-dgxnp-local')
10:10:45.144 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:45.151 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:10:45.154 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:45.154 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:45.156 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:10:45.165 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:10:45.172 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:45.178 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:10:45.181 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:10:45.185 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:10:45.191 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:45.222 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:10:45.256 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:45.281 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:10:45.298 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:10:45.298 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:10:45.305 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:10:45.318 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:10:45.332 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:10:45.332 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:10:45.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:10:45.340 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cae14f0a662473c04cf89c02ce11ece55e9dd89b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:10:45.371 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.372 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:10:45.372 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cae14f0a662473c04cf89c02ce11ece55e9dd89b' toSha1='null'
10:10:45.372 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:10:45.372 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:10:45.373 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:10:45.379 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 31773 bytes in 7 ms. path='/1/a'
10:10:45.385 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:45.418 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.425 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:10:45.435 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8ffa57cbfde0f96cffc3119ad7665b5f3fda12c0' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:10:45.448 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.450 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:10:45.450 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8ffa57cbfde0f96cffc3119ad7665b5f3fda12c0' toSha1='null'
10:10:45.450 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:10:45.450 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:10:45.451 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:10:45.458 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 102354 bytes in 8 ms. path='/1/b'
10:10:45.462 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:45.478 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.482 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:10:45.491 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f53f89262461ef6f445f7ca591d95ad72e14659d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:10:45.507 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.508 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:10:45.508 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f53f89262461ef6f445f7ca591d95ad72e14659d' toSha1='null'
10:10:45.508 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:10:45.508 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:10:45.509 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:10:45.514 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 20165 bytes in 6 ms. path='/1/c'
10:10:45.518 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:45.530 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.535 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:10:45.546 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d4d479e98fb1448154c292ed106fdbd9b7cb5311' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:10:45.560 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.562 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:10:45.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d4d479e98fb1448154c292ed106fdbd9b7cb5311' toSha1='null'
10:10:45.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:10:45.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:10:45.564 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:10:45.572 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 134843 bytes in 10 ms. path='/2/1/a'
10:10:45.577 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:45.591 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.594 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:10:45.602 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='40ddca6e1d893f3fa14c15934c7ed99a768963e8' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:10:45.616 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.617 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:10:45.617 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='40ddca6e1d893f3fa14c15934c7ed99a768963e8' toSha1='null'
10:10:45.618 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:10:45.618 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:10:45.620 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:10:45.627 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 181480 bytes in 9 ms. path='/2/1/b'
10:10:45.631 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:45.650 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.653 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:10:45.660 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d0cc3d052d8a8f9942edb302e53e633cf342a095' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:10:45.671 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.672 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:10:45.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d0cc3d052d8a8f9942edb302e53e633cf342a095' toSha1='null'
10:10:45.672 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:10:45.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:10:45.673 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:10:45.679 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 61192 bytes in 7 ms. path='/2/a'
10:10:45.683 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:45.694 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.697 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:10:45.706 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9d48d5860cac2879225f0c9c1fe4ed4e9208a0f4' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:10:45.717 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.719 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:10:45.719 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9d48d5860cac2879225f0c9c1fe4ed4e9208a0f4' toSha1='null'
10:10:45.719 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:10:45.719 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:10:45.722 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:10:45.732 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 356040 bytes in 13 ms. path='/3/a'
10:10:45.736 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:10:45.758 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.761 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:10:45.770 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d0f8cce43d5e05cdea2f0dc6545b111aac2bb740' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:10:45.781 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.783 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:10:45.783 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d0f8cce43d5e05cdea2f0dc6545b111aac2bb740' toSha1='null'
10:10:45.783 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:10:45.783 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:10:45.787 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:10:45.795 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 361840 bytes in 12 ms. path='/3/b'
10:10:45.799 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:10:45.823 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.826 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:10:45.835 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c82ffe4273c94c22a0327892c02cd767296c4237' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:10:45.849 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.850 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:10:45.851 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c82ffe4273c94c22a0327892c02cd767296c4237' toSha1='null'
10:10:45.851 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:10:45.851 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:10:45.852 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:10:45.859 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 210848 bytes in 8 ms. path='/3/c'
10:10:45.863 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:10:45.881 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.887 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:10:45.903 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9e69c5ebb09f5e44be3829bc06c99e2b610e4833' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:10:45.913 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.915 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:10:45.915 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9e69c5ebb09f5e44be3829bc06c99e2b610e4833' toSha1='null'
10:10:45.915 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:10:45.915 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:10:45.915 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:10:45.924 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 13344 bytes in 9 ms. path='/3/d'
10:10:45.928 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:10:45.936 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="cc1a37e2-f719-44a0-baa6-b34c04db0f2f"] on repo=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0
10:10:45.939 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:10:45.954 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=2
10:10:45.971 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f transaction.localRevision=57 remoteFromLocalRevision=2
10:10:45.978 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 from='target/repo/jz6ouny7-dgxnp-local' toID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote'
10:10:45.996 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:10:46.010 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:10:46.010 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:46.022 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f localRepositoryRevisionSynced=57
10:10:46.029 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 transaction.localRevision=5 remoteFromLocalRevision=57
10:10:46.036 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote' toID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 to='target/repo/jz6ouny7-dgxnp-local', fromRepoLocalSync=false
10:10:46.053 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:10:46.056 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:46.056 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:46.060 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=5
10:10:46.070 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f transaction.localRevision=60 remoteFromLocalRevision=5
10:10:46.072 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:46.087 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:46.087 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:46.088 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]getCloseDeferredMillis: closeDeferredMillis=20000
10:10:46.088 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:46.118 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:10:46.119 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:10:46.121 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
10:10:46.124 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
10:10:46.126 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:10:46.128 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
10:10:46.132 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
10:10:46.140 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
10:10:46.196 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:10:46.219 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:10:46.219 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote' toID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 to='target/repo/jz6ouny7-dgxnp-local', fromRepoLocalSync=true
10:10:46.220 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 ('target/repo/jz6ouny7-dgxnp-local')
10:10:46.225 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:10:46.238 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:10:46.254 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:10:46.268 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:10:46.271 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:10:46.274 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
10:10:46.278 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
10:10:46.281 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:10:46.284 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
10:10:46.288 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
10:10:46.291 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
10:10:46.295 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:10:46.304 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:10:46.326 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
10:10:46.326 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:10:46.339 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:10:46.387 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
10:10:46.388 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:10:46.408 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=8
10:10:46.416 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f transaction.localRevision=63 remoteFromLocalRevision=8
10:10:46.424 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 from='target/repo/jz6ouny7-dgxnp-local' toID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote'
10:10:46.439 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:10:46.447 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:10:46.447 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:10:46.448 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:10:46.452 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:10:46.452 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:10:46.467 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f localRepositoryRevisionSynced=63
10:10:46.476 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 transaction.localRevision=11 remoteFromLocalRevision=63
10:10:46.483 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=cc1a37e2-f719-44a0-baa6-b34c04db0f2f from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ouny8-xovuw-remote' toID=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 to='target/repo/jz6ouny7-dgxnp-local', fromRepoLocalSync=false
10:10:46.494 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:10:46.499 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:10:46.499 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:10:46.503 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f remoteRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 localRepositoryRevisionSynced=11
10:10:46.511 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7196f9fc-e8a1-42eb-aefa-72a50dae5ab0 remoteRepositoryId=cc1a37e2-f719-44a0-baa6-b34c04db0f2f transaction.localRevision=66 remoteFromLocalRevision=11
10:10:46.512 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:10:46.512 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4bf9523]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:10:46.523 [main] INFO c.c.c.l.LocalRepoManagerImpl - [447059e]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 292.753 sec - in co.codewizards.cloudstore.local.sync.RepoToRepoSyncTest
Results :
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.local ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.local ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO]
[INFO] --- maven-install-plugin:2.3.1:install (default-install) @ co.codewizards.cloudstore.local ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 251.0 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17.jar (203 KB at 14481.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17.pom (3 KB at 2565.4 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (399 B at 55.7 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 251.0 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (399 B at 194.8 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.081048-17-sources.jar (105 KB at 26069.1 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1003.9 KB/sec)
[INFO]
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.local ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local
[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.local/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore/site/co.codewizards.cloudstore.local
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.local ---
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/apidocs
[INFO]
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
file:/tmp/cloudstore-junk/ - Session: Opened
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site
[INFO] >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.local
[INFO] >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.local
file:/tmp/cloudstore-junk/ - Session: Disconnecting
file:/tmp/cloudstore-junk/ - Session: Disconnected
|
Help us localize this page