Console Output

l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:05.024 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.71%
10:34:05.062 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:05.087 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.087 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 6ec58d6a-16eb-4a54-9c56-89ac5c318a54
10:34:05.090 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:05.091 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2' toID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 to='target/repo/jz6pomnd-4cncu-local', fromRepoLocalSync=true
10:34:05.092 [pool-31-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 92ffa9a0-7f35-4813-aa62-8ec4b5748a42 ('target/repo/jz6pomnd-4cncu-local')
10:34:05.120 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:05.130 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:05.133 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:05.134 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:05.136 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:05.142 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:05.147 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:05.154 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:05.158 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:05.163 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:05.170 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:05.202 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:05.256 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:05.277 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:05.290 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:34:05.303 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:34:05.303 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:34:05.303 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:05.312 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='87d938894d218d5cf5fec1829c68d04283f5aba1' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:05.350 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.351 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:05.351 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='87d938894d218d5cf5fec1829c68d04283f5aba1' toSha1='null'
10:34:05.351 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:05.351 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:05.354 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:05.362 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 231300 bytes in 11 ms. path='/1/a'
10:34:05.368 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:05.410 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.417 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:05.425 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='99ab6493df3ca5344ca0343e4ced82bba47523b9' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:05.439 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.441 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:05.442 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='99ab6493df3ca5344ca0343e4ced82bba47523b9' toSha1='null'
10:34:05.442 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:05.443 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:05.447 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:05.457 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150420 bytes in 15 ms. path='/1/b'
10:34:05.461 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:05.483 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.492 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:34:05.499 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='97393c2bda6346163d16dac801fa123c095524a2' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:34:05.510 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.512 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:34:05.512 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='97393c2bda6346163d16dac801fa123c095524a2' toSha1='null'
10:34:05.512 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:34:05.512 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:34:05.517 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:34:05.527 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 467376 bytes in 15 ms. path='/a'
10:34:05.530 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:05.559 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:05.562 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:34:05.583 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=2
10:34:05.601 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 transaction.localRevision=19 remoteFromLocalRevision=2
10:34:05.605 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 from='target/repo/jz6pomnd-4cncu-local' toID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2'
10:34:05.624 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:34:05.634 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:05.634 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:05.645 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 localRepositoryRevisionSynced=19
10:34:05.653 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 transaction.localRevision=5 remoteFromLocalRevision=19
10:34:05.659 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2' toID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 to='target/repo/jz6pomnd-4cncu-local', fromRepoLocalSync=false
10:34:05.678 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:05.685 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:05.685 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:05.688 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=5
10:34:05.696 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 transaction.localRevision=22 remoteFromLocalRevision=5
10:34:05.698 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:05.715 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2e8a0c90]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:05.715 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2e8a0c90]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:05.715 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49fcd80e]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:05.716 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49fcd80e]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:05.743 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:05.744 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:05.745 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:34:05.749 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:05.753 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:05.787 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:34:05.790 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:34:05.802 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:34:05.822 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:05.862 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:05.863 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2' toID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 to='target/repo/jz6pomnd-4cncu-local', fromRepoLocalSync=true
10:34:05.864 [pool-32-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 92ffa9a0-7f35-4813-aa62-8ec4b5748a42 ('target/repo/jz6pomnd-4cncu-local')
10:34:05.867 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:05.874 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:34:05.883 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:05.895 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:05.898 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:05.901 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:05.906 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:05.911 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:05.924 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:05.928 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:05.934 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:34:05.943 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:05.955 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:05.972 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:05.984 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:34:05.985 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
10:34:05.996 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:34:05.996 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:34:05.996 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
10:34:06.007 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2330bffd1769cc89ed3a6a4127005e0db9a67647' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
10:34:06.022 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:06.023 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
10:34:06.023 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2330bffd1769cc89ed3a6a4127005e0db9a67647' toSha1='null'
10:34:06.023 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
10:34:06.023 [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:34:06.024 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
10:34:06.030 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 69240 bytes in 7 ms. path='/1/5/aaa'
10:34:06.035 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:34:06.047 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:06.050 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
10:34:06.061 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2182e602fb1d902260dcdaeeb83b449ba1e6f54e' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
10:34:06.074 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:06.076 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
10:34:06.076 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2182e602fb1d902260dcdaeeb83b449ba1e6f54e' toSha1='null'
10:34:06.076 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
10:34:06.076 [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:34:06.082 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
10:34:06.094 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 517132 bytes in 18 ms. path='/1/5/bbb'
10:34:06.099 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:34:06.128 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="6ec58d6a-16eb-4a54-9c56-89ac5c318a54"] on repo=92ffa9a0-7f35-4813-aa62-8ec4b5748a42
10:34:06.131 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:34:06.135 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=8
10:34:06.143 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 transaction.localRevision=35 remoteFromLocalRevision=8
10:34:06.151 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 from='target/repo/jz6pomnd-4cncu-local' toID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2'
10:34:06.164 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
10:34:06.167 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:06.167 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:06.170 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 localRepositoryRevisionSynced=35
10:34:06.173 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 transaction.localRevision=11 remoteFromLocalRevision=35
10:34:06.179 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pomnd-h66h3-remote/2' toID=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 to='target/repo/jz6pomnd-4cncu-local', fromRepoLocalSync=false
10:34:06.195 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:06.203 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:06.204 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:06.207 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 remoteRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 localRepositoryRevisionSynced=11
10:34:06.215 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=92ffa9a0-7f35-4813-aa62-8ec4b5748a42 remoteRepositoryId=6ec58d6a-16eb-4a54-9c56-89ac5c318a54 transaction.localRevision=38 remoteFromLocalRevision=11
10:34:06.217 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:06.217 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2e8a0c90]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:06.236 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49fcd80e]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:06.268 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-252ni-local'
10:34:06.273 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-252ni-local' locked successfully.
10:34:07.544 [closeDeferredTimer-11204840-3] INFO  c.c.c.l.LocalRepoManagerImpl - [11204840]_close: Shutting down real LocalRepoManager.
10:34:07.545 [closeDeferredTimer-a0374ab-3] INFO  c.c.c.l.LocalRepoManagerImpl - [a0374ab]_close: Shutting down real LocalRepoManager.
10:34:10.679 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]initPersistenceManagerFactory: repositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:10.681 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3375 ms.
10:34:10.706 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote'
10:34:10.711 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote' locked successfully.
10:34:14.663 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]initPersistenceManagerFactory: repositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7
10:34:14.669 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2278 ms.
10:34:14.777 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:14.784 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:14.795 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:34:14.854 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:14.874 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:34:14.912 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.47%
10:34:14.928 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:14.958 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.72%
10:34:14.988 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:15.013 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.013 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7
10:34:15.017 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:15.018 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote' toID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 to='target/repo/jz6pougr-252ni-local', fromRepoLocalSync=true
10:34:15.018 [pool-33-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 405eaba8-59cf-4c2f-92eb-21f2a385bef6 ('target/repo/jz6pougr-252ni-local')
10:34:15.046 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:15.051 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:15.056 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:15.058 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:15.059 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:15.063 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:15.067 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:15.073 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:15.078 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:15.083 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:15.090 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:15.119 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:15.156 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:15.181 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:15.194 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:34:15.194 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:34:15.204 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:15.215 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:34:15.232 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:34:15.232 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:34:15.233 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:15.239 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='61c5596765d44d70fa66b7b5db7d5cae0e4815b0' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:15.268 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.270 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:15.270 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='61c5596765d44d70fa66b7b5db7d5cae0e4815b0' toSha1='null'
10:34:15.270 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:15.270 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:15.274 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:15.282 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 387360 bytes in 12 ms. path='/1/a'
10:34:15.285 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:15.330 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.338 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:15.347 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='36142065b9ede15d16fa67853fb3b9a50dc4e229' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:15.360 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.361 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:15.361 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='36142065b9ede15d16fa67853fb3b9a50dc4e229' toSha1='null'
10:34:15.361 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:15.361 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:15.367 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:15.378 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 501424 bytes in 17 ms. path='/1/b'
10:34:15.382 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:15.409 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.413 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:34:15.421 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5e499f12369fab0ce0cc51f0f514b11c0a0ca7d9' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:34:15.434 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.437 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:34:15.437 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5e499f12369fab0ce0cc51f0f514b11c0a0ca7d9' toSha1='null'
10:34:15.437 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:34:15.437 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:34:15.442 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:34:15.452 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 442464 bytes in 15 ms. path='/1/c'
10:34:15.456 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:15.480 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.484 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:34:15.494 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ba021bf15513e6a82439b6814085f4a254f583b2' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:34:15.506 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.507 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:34:15.507 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ba021bf15513e6a82439b6814085f4a254f583b2' toSha1='null'
10:34:15.507 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:34:15.507 [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:34:15.515 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:34:15.530 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 691760 bytes in 23 ms. path='/2/1/a'
10:34:15.535 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:15.570 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.573 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:34:15.587 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b2546ff2d6cdeaf8af6d8d581621801e298dc317' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:34:15.601 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.603 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:34:15.603 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b2546ff2d6cdeaf8af6d8d581621801e298dc317' toSha1='null'
10:34:15.603 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:34:15.603 [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:34:15.605 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:34:15.616 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150442 bytes in 13 ms. path='/2/1/b'
10:34:15.622 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:15.637 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.640 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:34:15.649 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='354a93e4164ab61c89919c1eb7e885ed1d2f8c0f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:34:15.663 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.664 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:34:15.664 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='354a93e4164ab61c89919c1eb7e885ed1d2f8c0f' toSha1='null'
10:34:15.664 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:34:15.664 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:34:15.669 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:34:15.678 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 367003 bytes in 14 ms. path='/2/a'
10:34:15.682 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:15.705 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.709 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:34:15.718 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6e2cf951a3e318192d2a398e3de7a47e8ec4abbb' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:34:15.730 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.732 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:34:15.732 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6e2cf951a3e318192d2a398e3de7a47e8ec4abbb' toSha1='null'
10:34:15.732 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:34:15.732 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:34:15.735 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:34:15.743 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 244154 bytes in 11 ms. path='/3/a'
10:34:15.747 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:15.766 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.770 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:34:15.779 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6e382c4498e0275691a4425697e7cca971a29298' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:34:15.790 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.791 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:34:15.791 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6e382c4498e0275691a4425697e7cca971a29298' toSha1='null'
10:34:15.791 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:34:15.791 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:34:15.793 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:34:15.799 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 101984 bytes in 8 ms. path='/3/b'
10:34:15.803 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:15.816 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.819 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:34:15.827 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cb260d69fcfbbf12784ad4705b8a3ab7b8d98e72' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:34:15.839 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.840 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:34:15.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cb260d69fcfbbf12784ad4705b8a3ab7b8d98e72' toSha1='null'
10:34:15.840 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:34:15.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:34:15.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:34:15.856 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 491847 bytes in 16 ms. path='/3/c'
10:34:15.860 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:15.886 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.890 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:34:15.898 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e897cca059d836169f75d416015efb63ea696ad2' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:34:15.910 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.912 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:34:15.912 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e897cca059d836169f75d416015efb63ea696ad2' toSha1='null'
10:34:15.912 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:34:15.912 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:34:15.916 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:34:15.931 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 402944 bytes in 19 ms. path='/3/d'
10:34:15.935 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:34:15.960 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:15.963 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:34:15.983 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=2
10:34:15.996 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 transaction.localRevision=57 remoteFromLocalRevision=2
10:34:16.010 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 from='target/repo/jz6pougr-252ni-local' toID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote'
10:34:16.032 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:34:16.041 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:16.041 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:16.051 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 localRepositoryRevisionSynced=57
10:34:16.058 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 transaction.localRevision=5 remoteFromLocalRevision=57
10:34:16.064 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote' toID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 to='target/repo/jz6pougr-252ni-local', fromRepoLocalSync=false
10:34:16.084 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:16.086 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:16.086 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:16.090 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=5
10:34:16.101 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 transaction.localRevision=60 remoteFromLocalRevision=5
10:34:16.103 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:16.117 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:16.117 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:16.118 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:16.118 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:16.125 [closeDeferredTimer-11260edc-1] INFO  c.c.c.l.LocalRepoManagerImpl - [11260edc]_close: Shutting down real LocalRepoManager.
10:34:16.423 [closeDeferredTimer-2f5121ab-1] INFO  c.c.c.l.LocalRepoManagerImpl - [2f5121ab]_close: Shutting down real LocalRepoManager.
10:34:16.855 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:16.857 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:16.860 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:34:16.864 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:16.869 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:16.917 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:34:16.921 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:34:16.932 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:34:16.958 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:16.979 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:16.980 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote' toID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 to='target/repo/jz6pougr-252ni-local', fromRepoLocalSync=true
10:34:16.981 [pool-34-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 405eaba8-59cf-4c2f-92eb-21f2a385bef6 ('target/repo/jz6pougr-252ni-local')
10:34:16.991 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:17.015 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:34:17.037 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:34:17.052 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:17.053 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:17.055 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:17.059 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:17.064 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:17.080 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:17.085 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:17.093 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:34:17.104 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:17.169 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:17.193 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
10:34:17.193 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:17.248 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
10:34:17.264 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:34:17.282 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
10:34:17.286 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
10:34:17.286 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
10:34:17.287 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
10:34:17.299 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e8bdfe50486fbc8f73f6cf19ac0b8922dfd08bff' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
10:34:17.333 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.349 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
10:34:17.349 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e8bdfe50486fbc8f73f6cf19ac0b8922dfd08bff' toSha1='null'
10:34:17.349 [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:34:17.349 [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:34:17.350 [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:34:17.356 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 25709 bytes in 7 ms. path='/2/1/5/aaa'
10:34:17.362 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:34:17.441 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.447 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
10:34:17.465 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='06bae299db05051c031b311f9ad488892a160c4f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
10:34:17.541 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.544 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
10:34:17.544 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='06bae299db05051c031b311f9ad488892a160c4f' toSha1='null'
10:34:17.544 [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:34:17.544 [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:34:17.547 [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:34:17.559 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 142595 bytes in 15 ms. path='/2/1/5/bbb'
10:34:17.568 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:34:17.590 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.610 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
10:34:17.621 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='23e9dc4cf73ea4cdd186dc9e7a8dcbe1f4ae537b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
10:34:17.700 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.721 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
10:34:17.721 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='23e9dc4cf73ea4cdd186dc9e7a8dcbe1f4ae537b' toSha1='null'
10:34:17.721 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
10:34:17.721 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
10:34:17.723 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
10:34:17.731 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 98975 bytes in 10 ms. path='/3/e'
10:34:17.738 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
10:34:17.760 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7"] on repo=405eaba8-59cf-4c2f-92eb-21f2a385bef6
10:34:17.766 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=8
10:34:17.775 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 transaction.localRevision=79 remoteFromLocalRevision=8
10:34:17.782 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 from='target/repo/jz6pougr-252ni-local' toID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote'
10:34:17.797 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
10:34:17.800 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:34:17.800 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:17.800 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:17.803 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 localRepositoryRevisionSynced=79
10:34:17.807 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 transaction.localRevision=11 remoteFromLocalRevision=79
10:34:17.814 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pougr-ovrv3-remote' toID=405eaba8-59cf-4c2f-92eb-21f2a385bef6 to='target/repo/jz6pougr-252ni-local', fromRepoLocalSync=false
10:34:17.827 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:17.831 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:17.831 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:17.836 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 remoteRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 localRepositoryRevisionSynced=11
10:34:17.845 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=405eaba8-59cf-4c2f-92eb-21f2a385bef6 remoteRepositoryId=9fa82edf-4d9b-43ad-b7f0-3ab0cebe79b7 transaction.localRevision=82 remoteFromLocalRevision=11
10:34:17.847 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:17.847 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:17.865 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:17.943 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-vatdr-local'
10:34:17.949 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-vatdr-local' locked successfully.
10:34:20.297 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]initPersistenceManagerFactory: repositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:20.298 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1291 ms.
10:34:20.315 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote'
10:34:20.322 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote' locked successfully.
10:34:24.400 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]initPersistenceManagerFactory: repositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46
10:34:24.407 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2378 ms.
10:34:24.492 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:24.498 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:24.507 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:34:24.561 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:24.573 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:34:24.604 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.41%
10:34:24.618 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:24.650 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
10:34:24.676 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:24.700 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:24.700 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 743c318f-8a46-48d9-b31a-f5f9a0dede46
10:34:24.704 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:24.705 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=743c318f-8a46-48d9-b31a-f5f9a0dede46 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote' toID=d52b702a-4393-4605-adcb-4012ccdd1c76 to='target/repo/jz6pp3h2-vatdr-local', fromRepoLocalSync=true
10:34:24.706 [pool-35-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing d52b702a-4393-4605-adcb-4012ccdd1c76 ('target/repo/jz6pp3h2-vatdr-local')
10:34:24.754 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:24.763 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:24.764 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:24.767 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:24.771 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:24.780 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:24.783 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:24.790 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:24.793 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:24.797 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:24.803 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:24.830 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:24.863 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:24.886 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:24.898 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:34:24.898 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:34:24.906 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:24.916 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:34:24.937 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:34:24.937 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:34:24.937 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:24.945 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f660de10895bf5efa04c2c88c7a386030e631081' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:24.980 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:24.981 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:24.981 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f660de10895bf5efa04c2c88c7a386030e631081' toSha1='null'
10:34:24.982 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:24.982 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:24.982 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:24.991 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 64209 bytes in 9 ms. path='/1/a'
10:34:24.996 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:25.028 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.035 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:25.044 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='27c720ad54c706d35f02d990701c53118187710b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:25.056 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.057 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:25.057 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='27c720ad54c706d35f02d990701c53118187710b' toSha1='null'
10:34:25.057 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:25.057 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:25.058 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:25.062 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 25824 bytes in 5 ms. path='/1/b'
10:34:25.066 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:25.075 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.078 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:34:25.086 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='356d7598946f35635e43ec44a9b6adb139835330' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:34:25.098 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.099 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:34:25.099 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='356d7598946f35635e43ec44a9b6adb139835330' toSha1='null'
10:34:25.099 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:34:25.099 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:34:25.107 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:34:25.118 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 497500 bytes in 19 ms. path='/1/c'
10:34:25.121 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:25.146 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.150 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:34:25.163 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='256c6325248e877ef82c668d009032107e76407f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:34:25.175 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.177 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:34:25.177 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='256c6325248e877ef82c668d009032107e76407f' toSha1='null'
10:34:25.177 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:34:25.177 [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:34:25.182 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:34:25.194 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 464360 bytes in 17 ms. path='/2/1/a'
10:34:25.198 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:25.223 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.227 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:34:25.237 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1420fe6d79c276dfabe14dd89b1c7d6236f6668c' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:34:25.251 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.252 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:34:25.252 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1420fe6d79c276dfabe14dd89b1c7d6236f6668c' toSha1='null'
10:34:25.252 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:34:25.252 [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:34:25.254 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:34:25.261 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 152874 bytes in 9 ms. path='/2/1/b'
10:34:25.266 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:25.280 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.285 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:34:25.295 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d687750f79c71e6e64f2a5d3244a0dc4430d5890' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:34:25.306 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.308 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:34:25.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d687750f79c71e6e64f2a5d3244a0dc4430d5890' toSha1='null'
10:34:25.308 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:34:25.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:34:25.312 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:34:25.320 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 315680 bytes in 12 ms. path='/2/a'
10:34:25.324 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:25.344 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.347 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:34:25.355 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1b5a9df7cb12f12c19401a10c6ab04c8106cb1c0' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:34:25.369 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.370 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:34:25.370 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1b5a9df7cb12f12c19401a10c6ab04c8106cb1c0' toSha1='null'
10:34:25.370 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:34:25.370 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:34:25.371 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:34:25.377 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 83562 bytes in 7 ms. path='/3/a'
10:34:25.382 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:25.396 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.399 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:34:25.412 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6d3262ddeafdec34e332ad74ed07f7c98e3a82c6' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:34:25.424 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.425 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:34:25.425 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6d3262ddeafdec34e332ad74ed07f7c98e3a82c6' toSha1='null'
10:34:25.425 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:34:25.425 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:34:25.430 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:34:25.439 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 355418 bytes in 14 ms. path='/3/b'
10:34:25.444 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:25.468 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.475 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:34:25.491 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c32314f0bb10a52e158cf2349c509465ddd10de9' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:34:25.503 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.504 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:34:25.504 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c32314f0bb10a52e158cf2349c509465ddd10de9' toSha1='null'
10:34:25.504 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:34:25.504 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:34:25.511 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:34:25.522 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 563108 bytes in 18 ms. path='/3/c'
10:34:25.526 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:25.555 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.559 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:34:25.572 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='146f089252b87b37295eb6e925090c27824db387' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:34:25.583 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.584 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:34:25.584 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='146f089252b87b37295eb6e925090c27824db387' toSha1='null'
10:34:25.584 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:34:25.584 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:34:25.585 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:34:25.594 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 20631 bytes in 10 ms. path='/3/d'
10:34:25.598 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:34:25.606 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="743c318f-8a46-48d9-b31a-f5f9a0dede46"] on repo=d52b702a-4393-4605-adcb-4012ccdd1c76
10:34:25.609 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:34:25.624 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=2
10:34:25.638 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 transaction.localRevision=57 remoteFromLocalRevision=2
10:34:25.645 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=d52b702a-4393-4605-adcb-4012ccdd1c76 from='target/repo/jz6pp3h2-vatdr-local' toID=743c318f-8a46-48d9-b31a-f5f9a0dede46 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote'
10:34:25.664 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:34:25.677 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:25.678 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:25.687 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 localRepositoryRevisionSynced=57
10:34:25.695 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 transaction.localRevision=5 remoteFromLocalRevision=57
10:34:25.700 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=743c318f-8a46-48d9-b31a-f5f9a0dede46 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote' toID=d52b702a-4393-4605-adcb-4012ccdd1c76 to='target/repo/jz6pp3h2-vatdr-local', fromRepoLocalSync=false
10:34:25.719 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:25.722 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:25.722 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:25.725 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=5
10:34:25.735 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 transaction.localRevision=60 remoteFromLocalRevision=5
10:34:25.737 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:25.756 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:25.756 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:25.756 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:25.756 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:25.802 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:25.803 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:25.805 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:34:25.808 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:25.812 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:25.860 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:34:25.868 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:34:25.872 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:34:25.884 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:25.907 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:25.907 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=743c318f-8a46-48d9-b31a-f5f9a0dede46 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote' toID=d52b702a-4393-4605-adcb-4012ccdd1c76 to='target/repo/jz6pp3h2-vatdr-local', fromRepoLocalSync=true
10:34:25.907 [pool-36-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing d52b702a-4393-4605-adcb-4012ccdd1c76 ('target/repo/jz6pp3h2-vatdr-local')
10:34:25.912 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:25.927 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:34:25.947 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:34:25.961 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:25.962 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:25.964 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:25.968 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:25.972 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:25.977 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:25.980 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:25.984 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:25.991 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:26.002 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:26.022 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
10:34:26.022 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:26.036 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
10:34:26.037 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:34:26.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
10:34:26.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:34:26.092 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=8
10:34:26.103 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 transaction.localRevision=63 remoteFromLocalRevision=8
10:34:26.109 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=d52b702a-4393-4605-adcb-4012ccdd1c76 from='target/repo/jz6pp3h2-vatdr-local' toID=743c318f-8a46-48d9-b31a-f5f9a0dede46 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote'
10:34:26.121 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:34:26.129 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:34:26.129 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:34:26.129 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:34:26.134 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:34:26.134 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:26.150 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 localRepositoryRevisionSynced=63
10:34:26.153 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 transaction.localRevision=11 remoteFromLocalRevision=63
10:34:26.160 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=743c318f-8a46-48d9-b31a-f5f9a0dede46 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pp3h2-n7t7n-remote' toID=d52b702a-4393-4605-adcb-4012ccdd1c76 to='target/repo/jz6pp3h2-vatdr-local', fromRepoLocalSync=false
10:34:26.174 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:26.179 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:26.179 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:26.183 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 remoteRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 localRepositoryRevisionSynced=11
10:34:26.190 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d52b702a-4393-4605-adcb-4012ccdd1c76 remoteRepositoryId=743c318f-8a46-48d9-b31a-f5f9a0dede46 transaction.localRevision=66 remoteFromLocalRevision=11
10:34:26.192 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:26.192 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:26.207 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:26.219 [closeDeferredTimer-2e8a0c90-2] INFO  c.c.c.l.LocalRepoManagerImpl - [2e8a0c90]_close: Shutting down real LocalRepoManager.
10:34:26.537 [closeDeferredTimer-49fcd80e-2] INFO  c.c.c.l.LocalRepoManagerImpl - [49fcd80e]_close: Shutting down real LocalRepoManager.
10:34:26.917 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-r9agw-local'
10:34:26.929 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-r9agw-local' locked successfully.
10:34:31.010 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]initPersistenceManagerFactory: repositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:31.012 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1521 ms.
10:34:31.032 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote'
10:34:31.038 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote' locked successfully.
10:34:34.824 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]initPersistenceManagerFactory: repositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1
10:34:34.831 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2198 ms.
10:34:34.934 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:34.940 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:34.951 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:34:35.006 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:35.018 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:34:35.055 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.40%
10:34:35.074 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:35.102 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.70%
10:34:35.131 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:35.157 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.157 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: ba7f436c-fb3c-4454-8581-2c6ba32ba0c1
10:34:35.160 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:35.161 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2' toID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a to='target/repo/jz6ppaeb-r9agw-local', fromRepoLocalSync=true
10:34:35.162 [pool-37-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 2dcfde45-f7e0-4466-8860-bfc6fbfd713a ('target/repo/jz6ppaeb-r9agw-local')
10:34:35.196 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:35.205 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:35.207 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:35.210 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:35.215 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:35.220 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:35.224 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:35.232 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:35.235 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:35.241 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:35.247 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:35.278 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:35.326 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:35.348 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:35.360 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:34:35.365 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:34:35.365 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:34:35.365 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:35.373 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d1acd0de7a57699a5c0d9c907200a12f8aefa40b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:35.409 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.411 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:35.411 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d1acd0de7a57699a5c0d9c907200a12f8aefa40b' toSha1='null'
10:34:35.411 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:35.411 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:35.418 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:35.431 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 628019 bytes in 20 ms. path='/1/a'
10:34:35.435 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:35.487 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.494 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:35.505 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4bcbea2b35513927b67e08e0a9d325e25c121e89' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:35.517 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.519 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:35.519 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4bcbea2b35513927b67e08e0a9d325e25c121e89' toSha1='null'
10:34:35.519 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:35.519 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:35.521 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:35.527 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150024 bytes in 8 ms. path='/1/b'
10:34:35.531 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:35.545 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.548 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:34:35.556 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='90d669521cbce9cbc04a7fe6eeef43a4fd5b2936' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:34:35.568 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.569 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:34:35.570 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='90d669521cbce9cbc04a7fe6eeef43a4fd5b2936' toSha1='null'
10:34:35.570 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:34:35.570 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:34:35.577 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:34:35.588 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 633860 bytes in 18 ms. path='/a'
10:34:35.591 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:35.625 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="ba7f436c-fb3c-4454-8581-2c6ba32ba0c1"] on repo=2dcfde45-f7e0-4466-8860-bfc6fbfd713a
10:34:35.630 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:34:35.647 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=2
10:34:35.660 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 transaction.localRevision=19 remoteFromLocalRevision=2
10:34:35.664 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a from='target/repo/jz6ppaeb-r9agw-local' toID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2'
10:34:35.682 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:34:35.693 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:35.694 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:35.703 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 localRepositoryRevisionSynced=19
10:34:35.713 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a transaction.localRevision=5 remoteFromLocalRevision=19
10:34:35.720 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2' toID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a to='target/repo/jz6ppaeb-r9agw-local', fromRepoLocalSync=false
10:34:35.736 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:35.741 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:35.741 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:35.745 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=5
10:34:35.753 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 transaction.localRevision=22 remoteFromLocalRevision=5
10:34:35.755 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:35.774 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:35.774 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:35.774 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:35.774 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:35.812 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:35.813 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:35.814 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:34:35.817 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:35.821 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:35.863 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:34:35.866 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:34:35.869 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:34:35.881 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:35.900 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:35.900 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2' toID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a to='target/repo/jz6ppaeb-r9agw-local', fromRepoLocalSync=true
10:34:35.901 [pool-38-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 2dcfde45-f7e0-4466-8860-bfc6fbfd713a ('target/repo/jz6ppaeb-r9agw-local')
10:34:35.905 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:35.910 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:34:35.917 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:35.924 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:35.926 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:35.928 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:35.934 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:35.944 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:35.950 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:35.953 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:35.957 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:35.964 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:35.976 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:35.999 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
10:34:36.000 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:36.013 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:34:36.048 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
10:34:36.048 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
10:34:36.066 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=8
10:34:36.075 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 transaction.localRevision=25 remoteFromLocalRevision=8
10:34:36.081 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a from='target/repo/jz6ppaeb-r9agw-local' toID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2'
10:34:36.092 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
10:34:36.101 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:34:36.102 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:34:36.107 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:34:36.107 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:36.124 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 localRepositoryRevisionSynced=25
10:34:36.128 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a transaction.localRevision=11 remoteFromLocalRevision=25
10:34:36.135 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ppaeb-kq6uc-remote/2' toID=2dcfde45-f7e0-4466-8860-bfc6fbfd713a to='target/repo/jz6ppaeb-r9agw-local', fromRepoLocalSync=false
10:34:36.150 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:36.157 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:36.157 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:36.160 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 remoteRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a localRepositoryRevisionSynced=11
10:34:36.168 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2dcfde45-f7e0-4466-8860-bfc6fbfd713a remoteRepositoryId=ba7f436c-fb3c-4454-8581-2c6ba32ba0c1 transaction.localRevision=28 remoteFromLocalRevision=11
10:34:36.169 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:36.169 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [79771d0e]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:36.184 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7e3b918d]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:36.204 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-sdig9-local'
10:34:36.210 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-sdig9-local' locked successfully.
10:34:37.847 [closeDeferredTimer-4f718d88-2] INFO  c.c.c.l.LocalRepoManagerImpl - [4f718d88]_close: Shutting down real LocalRepoManager.
10:34:38.144 [closeDeferredTimer-2329c86f-2] INFO  c.c.c.l.LocalRepoManagerImpl - [2329c86f]_close: Shutting down real LocalRepoManager.
10:34:41.514 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]initPersistenceManagerFactory: repositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:41.516 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 4172 ms.
10:34:41.534 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote'
10:34:41.541 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote' locked successfully.
10:34:45.387 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]initPersistenceManagerFactory: repositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457
10:34:45.395 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2301 ms.
10:34:45.494 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:45.500 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:45.510 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:34:45.564 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:45.584 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:34:45.610 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.54%
10:34:45.622 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:45.646 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
10:34:45.669 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:45.695 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:45.695 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457
10:34:45.699 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:45.700 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote' toID=b620bbf7-0c72-415d-83be-d12d82a117c0 to='target/repo/jz6pphkb-sdig9-local', fromRepoLocalSync=true
10:34:45.700 [pool-39-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b620bbf7-0c72-415d-83be-d12d82a117c0 ('target/repo/jz6pphkb-sdig9-local')
10:34:45.701 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:45.704 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:45.708 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:45.723 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:45.736 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:45.748 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:45.753 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:45.758 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:45.760 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:45.765 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:45.773 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:45.808 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:45.841 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:45.864 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:45.877 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:34:45.877 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:34:45.885 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:45.894 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:34:45.907 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:34:45.907 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:34:45.907 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:45.915 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5a1fdf728ee570f825f7d532910ce69dae52a1ab' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:45.951 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:45.952 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:45.953 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5a1fdf728ee570f825f7d532910ce69dae52a1ab' toSha1='null'
10:34:45.953 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:45.953 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:45.959 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:45.971 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 473925 bytes in 18 ms. path='/1/a'
10:34:45.975 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:46.022 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.030 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:46.043 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='04a5b7f1309ab6464a4f19e6416e5931581116e5' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:46.054 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.056 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:46.056 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='04a5b7f1309ab6464a4f19e6416e5931581116e5' toSha1='null'
10:34:46.056 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:46.056 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:46.056 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:46.060 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 5184 bytes in 4 ms. path='/1/b'
10:34:46.065 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:46.074 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.078 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:34:46.088 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='04ab9f04d487b96ea81e3e7667b7ad1fcc0ecceb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:34:46.102 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.103 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:34:46.103 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='04ab9f04d487b96ea81e3e7667b7ad1fcc0ecceb' toSha1='null'
10:34:46.104 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:34:46.104 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:34:46.110 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:34:46.121 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 562932 bytes in 17 ms. path='/1/c'
10:34:46.125 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:46.156 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.163 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:34:46.179 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c56c07bba89b971ad22cb281d1591b9462042bb7' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:34:46.193 [closeDeferredTimer-6ca8f93f-2] INFO  c.c.c.l.LocalRepoManagerImpl - [6ca8f93f]_close: Shutting down real LocalRepoManager.
10:34:46.494 [closeDeferredTimer-6bfa24b0-2] INFO  c.c.c.l.LocalRepoManagerImpl - [6bfa24b0]_close: Shutting down real LocalRepoManager.
10:34:46.862 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.871 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:34:46.871 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c56c07bba89b971ad22cb281d1591b9462042bb7' toSha1='null'
10:34:46.871 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:34:46.872 [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:34:46.876 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:34:46.887 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 95056 bytes in 15 ms. path='/2/1/a'
10:34:46.893 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:46.913 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.920 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:34:46.938 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fe0c36f118e9d8e8ef31012e8811485111885ddc' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:34:46.975 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:46.979 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:34:46.979 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fe0c36f118e9d8e8ef31012e8811485111885ddc' toSha1='null'
10:34:46.980 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:34:46.980 [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:34:46.985 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:34:46.996 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 347130 bytes in 16 ms. path='/2/1/b'
10:34:47.004 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:47.103 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.113 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:34:47.124 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0b02987cbc23326e14ea636db1d66808cd185033' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:34:47.190 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.194 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:34:47.194 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0b02987cbc23326e14ea636db1d66808cd185033' toSha1='null'
10:34:47.194 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:34:47.194 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:34:47.196 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:34:47.202 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 135608 bytes in 8 ms. path='/2/a'
10:34:47.206 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:47.239 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.247 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:34:47.258 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='49d72387d51703a416c89b12a43ecdc4510c5793' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:34:47.284 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.299 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:34:47.299 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='49d72387d51703a416c89b12a43ecdc4510c5793' toSha1='null'
10:34:47.299 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:34:47.300 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:34:47.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:34:47.308 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 25740 bytes in 8 ms. path='/3/a'
10:34:47.315 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:47.327 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.332 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:34:47.343 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ab0fae7e2aac10b134313a99f6aa5df3cc15fe59' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:34:47.408 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.413 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:34:47.413 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ab0fae7e2aac10b134313a99f6aa5df3cc15fe59' toSha1='null'
10:34:47.413 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:34:47.413 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:34:47.423 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:34:47.432 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 427584 bytes in 19 ms. path='/3/b'
10:34:47.436 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:47.507 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.596 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:34:47.607 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a4afa6fb1b0098909097ed21a76389a5eafb11bb' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:34:47.630 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.634 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:34:47.634 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a4afa6fb1b0098909097ed21a76389a5eafb11bb' toSha1='null'
10:34:47.635 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:34:47.635 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:34:47.640 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:34:47.649 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 194054 bytes in 14 ms. path='/3/c'
10:34:47.654 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:47.670 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.706 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:34:47.722 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6d1696caf5dfeb838d8918cdff5bb295fec44570' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:34:47.736 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.777 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:34:47.777 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6d1696caf5dfeb838d8918cdff5bb295fec44570' toSha1='null'
10:34:47.777 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:34:47.777 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:34:47.778 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:34:47.790 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 23936 bytes in 13 ms. path='/3/d'
10:34:47.794 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:34:47.811 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457"] on repo=b620bbf7-0c72-415d-83be-d12d82a117c0
10:34:47.835 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:34:47.868 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=2
10:34:47.927 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 transaction.localRevision=57 remoteFromLocalRevision=2
10:34:47.975 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b620bbf7-0c72-415d-83be-d12d82a117c0 from='target/repo/jz6pphkb-sdig9-local' toID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote'
10:34:48.003 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:34:48.022 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:48.022 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:48.034 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 localRepositoryRevisionSynced=57
10:34:48.043 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 transaction.localRevision=5 remoteFromLocalRevision=57
10:34:48.048 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote' toID=b620bbf7-0c72-415d-83be-d12d82a117c0 to='target/repo/jz6pphkb-sdig9-local', fromRepoLocalSync=false
10:34:48.063 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:48.065 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:48.065 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:48.068 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=5
10:34:48.076 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 transaction.localRevision=60 remoteFromLocalRevision=5
10:34:48.078 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:48.090 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:48.091 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:48.091 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:48.091 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:48.133 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:48.134 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:48.136 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
10:34:48.139 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
10:34:48.140 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:48.142 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
10:34:48.145 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
10:34:48.152 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
10:34:48.210 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:48.226 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:48.226 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote' toID=b620bbf7-0c72-415d-83be-d12d82a117c0 to='target/repo/jz6pphkb-sdig9-local', fromRepoLocalSync=true
10:34:48.227 [pool-40-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b620bbf7-0c72-415d-83be-d12d82a117c0 ('target/repo/jz6pphkb-sdig9-local')
10:34:48.240 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:48.261 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:34:48.277 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:34:48.291 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:48.294 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:48.298 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
10:34:48.302 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
10:34:48.306 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:48.310 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
10:34:48.314 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
10:34:48.317 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
10:34:48.321 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:48.331 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:48.351 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
10:34:48.352 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:48.367 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:34:48.414 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
10:34:48.416 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:34:48.431 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=8
10:34:48.439 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 transaction.localRevision=63 remoteFromLocalRevision=8
10:34:48.443 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b620bbf7-0c72-415d-83be-d12d82a117c0 from='target/repo/jz6pphkb-sdig9-local' toID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote'
10:34:48.456 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:34:48.464 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:34:48.464 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:34:48.464 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:34:48.468 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:34:48.468 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:48.481 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 localRepositoryRevisionSynced=63
10:34:48.485 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 transaction.localRevision=11 remoteFromLocalRevision=63
10:34:48.492 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6pphkb-ev8ty-remote' toID=b620bbf7-0c72-415d-83be-d12d82a117c0 to='target/repo/jz6pphkb-sdig9-local', fromRepoLocalSync=false
10:34:48.506 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:48.512 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:48.512 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:48.516 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 remoteRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 localRepositoryRevisionSynced=11
10:34:48.526 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b620bbf7-0c72-415d-83be-d12d82a117c0 remoteRepositoryId=4fd0e2bd-4e2c-4aab-b3ff-9f01d63ad457 transaction.localRevision=66 remoteFromLocalRevision=11
10:34:48.527 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:48.528 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [24024f94]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:48.535 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [682912a9]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 310.631 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 334.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.083450-18.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.083450-18.jar (203 KB at 28963.3 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.083450-18.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.083450-18.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 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/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 502.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 389.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.083450-18-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.083450-18-sources.jar (105 KB at 10427.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/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 502.0 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