Regression

co.codewizards.cloudstore.test.repotorepo.PathPrefixedConfigInheritanceRepoToRepoSyncIT.parentConfigWithLocalPathPrefix

Failing for the past 1 build (Since Unstable#846 )
Took 32 sec.

Error Message

java.io.FileNotFoundException: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/File.715f1916-93d5-4664-b6ff-32a936ae81a9.done.bin (No such file or directory)

Stacktrace

java.lang.RuntimeException: java.io.FileNotFoundException: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/File.715f1916-93d5-4664-b6ff-32a936ae81a9.done.bin (No such file or directory)
	at java.io.FileOutputStream.open0(Native Method)
	at java.io.FileOutputStream.open(FileOutputStream.java:270)
	at java.io.FileOutputStream.<init>(FileOutputStream.java:213)
	at co.codewizards.cloudstore.core.repo.sync.DoneMarker.<init>(DoneMarker.java:33)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.getDoneMarker(RepoToRepoSync.java:406)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.isDone(RepoToRepoSync.java:467)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.sync(RepoToRepoSync.java:372)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.sync(RepoToRepoSync.java:268)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.sync(RepoToRepoSync.java:248)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.syncUp(RepoToRepoSync.java:181)
	at co.codewizards.cloudstore.core.repo.sync.RepoToRepoSync.sync(RepoToRepoSync.java:158)
	at co.codewizards.cloudstore.test.repotorepo.PathPrefixedConfigInheritanceRepoToRepoSyncIT.parentConfigWithLocalPathPrefix(PathPrefixedConfigInheritanceRepoToRepoSyncIT.java:174)

Standard Output

08:13:18.498 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local'
08:13:18.500 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local' locked successfully.
08:13:29.706 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]initPersistenceManagerFactory: repositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:29.717 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 6556 ms.
08:13:29.850 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns45-d5a57-remote'
08:13:29.853 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns45-d5a57-remote' locked successfully.
08:13:37.869 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]initPersistenceManagerFactory: repositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:37.880 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 5468 ms.
08:13:38.600 [closeDeferredTimer-59309333-1] INFO  c.c.c.l.LocalRepoManagerImpl - [59309333]_close: Shutting down real LocalRepoManager.
08:13:38.601 [main] INFO  c.c.c.l.s.LocalServer - logMemoryStats: max=3.5 GiB, allocated=634.0 MiB, used=43.0 MiB, available=3.4 GiB
08:13:39.271 [closeDeferredTimer-8bffb8b-1] INFO  c.c.c.l.LocalRepoManagerImpl - [8bffb8b]_close: Shutting down real LocalRepoManager.
Successfully requested to connect the following local and remote repositories:

  localRepository.repositoryId = fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
  localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local
  localRepository.publicKeySha1 = 06:C9:DA:BF:BA:86:FE:EB:8B:07:7B:3A:D0:79:0C:F0:08:60:9A:22

  remoteRepository.repositoryId = 715f1916-93d5-4664-b6ff-32a936ae81a9
  remoteRepository.remoteRoot = https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9
  remoteRepository.publicKeySha1 = CE:F1:84:A4:B4:80:D0:D8:F1:58:FC:BF:D9:FA:21:5D:71:64:18:11

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

  cloudstore dropRepoConnection fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:41.733 [main] INFO  c.c.c.l.s.LocalServer - logMemoryStats: max=3.5 GiB, allocated=638.0 MiB, used=39.4 MiB, available=3.4 GiB
Successfully accepted the connection request for the following local and remote repositories:

  localRepository.repositoryId = 715f1916-93d5-4664-b6ff-32a936ae81a9
  localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns45-d5a57-remote
  localRepository.publicKeySha1 = CE:F1:84:A4:B4:80:D0:D8:F1:58:FC:BF:D9:FA:21:5D:71:64:18:11

  remoteRepository.repositoryId = fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
  remoteRepository.publicKeySha1 = 06:C9:DA:BF:BA:86:FE:EB:8B:07:7B:3A:D0:79:0C:F0:08:60:9A:22

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

  cloudstore dropRepoConnection 715f1916-93d5-4664-b6ff-32a936ae81a9 fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:42.459 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 1.00%
08:13:42.478 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 50.00%
08:13:42.510 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 58.00%
08:13:42.659 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 63.61%
08:13:42.716 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 74.34%
08:13:42.842 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 79.38%
08:13:42.906 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 90.67%
08:13:42.984 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 95.69%
08:13:43.132 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Local sync...: 100.00%
08:13:43.673 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 0.50%
08:13:43.675 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=715f1916-93d5-4664-b6ff-32a936ae81a9 from='https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9' toID=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 to='target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1', fromRepoLocalSync=true
08:13:43.678 [pool-9-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 ('target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1')
08:13:43.726 [pool-9-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 12.94%
08:13:43.768 [pool-9-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 19.03%
08:13:43.848 [pool-9-thread-1] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 24.11%
08:13:43.949 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.-1.xml.gz
08:13:44.170 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 serverRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 renewalDateTime=2019-08-11T06:43:43.973Z expiryDateTime=2019-08-11T07:13:43.973Z
08:13:44.248 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:13:44.279 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:13:44.311 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
08:13:44.327 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:13:44.453 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:13:44.464 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:44.465 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:44.465 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
08:13:44.466 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:13:44.489 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:13:44.563 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:13:44.564 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:13:44.564 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:13:44.564 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:13:44.577 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.-1.xml.gz
08:13:44.580 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 31.84%
08:13:44.581 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:13:44.666 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 40.80%
08:13:44.666 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 49.75%
08:13:44.842 [qtp1351792121-30] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=2
08:13:44.932 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 transaction.localRevision=4 remoteFromLocalRevision=2
08:13:44.991 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 from='target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1' toID=715f1916-93d5-4664-b6ff-32a936ae81a9 to='https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9'
08:13:45.099 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:13:45.103 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:45.103 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:45.103 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: /2/1 {11 11ä11#+} 1
08:13:45.103 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=4
08:13:45.130 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:13:45.310 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:13:45.311 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: skipped non-existing configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/2/.cloudstore.properties
08:13:45.311 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: skipped non-existing configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore.properties
08:13:45.311 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: skipped non-existing configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/parent.properties
08:13:45.312 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - isFileModifiedAfterLastSync: returning false
08:13:45.312 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - isConfigFileDeletedAfterLastSync: returning false
08:13:45.312 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:13:45.312 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:13:45.314 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 56.31%
08:13:45.331 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 62.42%
08:13:45.334 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 68.52%
08:13:45.334 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
08:13:45.476 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='145ea3bff52ee9b5bc370c1c925818351fded169' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
08:13:45.764 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="fcfd93d7-1fe1-4f67-8197-bc8ce79b6146"] on repo=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:45.841 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
08:13:45.983 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 67342 bytes in 142 ms. path='/a'
08:13:46.052 [qtp1351792121-25] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:13:46.275 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="fcfd93d7-1fe1-4f67-8197-bc8ce79b6146"] on repo=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:46.327 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/b'
08:13:46.418 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='97cdc20cf2148b7f555b031d8f80f3ae6e398e67' to.sha1='<NoInstanceOf_NormalFileDto>'): /b
08:13:46.572 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="fcfd93d7-1fe1-4f67-8197-bc8ce79b6146"] on repo=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:46.614 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/b'
08:13:46.851 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 520605 bytes in 237 ms. path='/b'
08:13:46.902 [qtp1351792121-23] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:13:46.980 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="fcfd93d7-1fe1-4f67-8197-bc8ce79b6146"] on repo=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:46.998 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
08:13:47.063 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 localRepositoryRevisionSynced=4
08:13:47.157 [qtp1351792121-30] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 transaction.localRevision=11 remoteFromLocalRevision=4
08:13:47.185 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=715f1916-93d5-4664-b6ff-32a936ae81a9 from='https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9' toID=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 to='target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1', fromRepoLocalSync=false
08:13:47.186 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.null.xml.gz
08:13:47.299 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:13:47.302 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:47.302 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:47.302 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
08:13:47.302 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=11
08:13:47.304 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:13:47.306 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:13:47.307 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:13:47.307 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:13:47.308 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:13:47.315 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.null.xml.gz
08:13:47.315 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
08:13:47.315 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
08:13:47.403 [qtp1351792121-92] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=11
08:13:47.441 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 transaction.localRevision=11 remoteFromLocalRevision=11
08:13:47.474 [main] INFO  c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
08:13:48.525 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 0.50%
08:13:48.526 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=715f1916-93d5-4664-b6ff-32a936ae81a9 from='https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9' toID=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 to='target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1', fromRepoLocalSync=true
08:13:48.526 [pool-10-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 ('target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1')
08:13:48.539 [pool-10-thread-1] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 12.94%
08:13:48.552 [pool-10-thread-1] INFO  c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1934 ms.
08:13:50.545 [pool-10-thread-1] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 19.03%
08:13:50.859 [pool-10-thread-1] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 24.17%
08:13:50.933 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.11.xml.gz
08:13:50.975 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:13:50.978 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:13:50.980 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
08:13:50.990 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:13:50.993 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
08:13:50.995 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
08:13:51.009 [pool-4-thread-1] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:13:51.030 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:13:51.042 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:51.042 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:51.042 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: 
08:13:51.042 [pool-4-thread-1] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=11 localRepositoryRevisionInProgress=13
08:13:51.056 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:13:51.058 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:13:51.058 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:13:51.058 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:13:51.058 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:13:51.069 [main] INFO  c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/tmp/ChangeSetDto.715f1916-93d5-4664-b6ff-32a936ae81a9.11.xml.gz
08:13:51.070 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 38.56%
08:13:51.070 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 49.75%
08:13:51.106 [qtp1351792121-25] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 remoteRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 localRepositoryRevisionSynced=13
08:13:51.124 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 transaction.localRevision=13 remoteFromLocalRevision=13
08:13:51.156 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 from='target/repo/jz6kns44-xsyf1-local/2/1 {11 11ä11#+} 1' toID=715f1916-93d5-4664-b6ff-32a936ae81a9 to='https://localhost:41557/715f1916-93d5-4664-b6ff-32a936ae81a9'
08:13:51.234 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:13:51.236 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: fcfd93d7-1fe1-4f67-8197-bc8ce79b6146
08:13:51.236 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 715f1916-93d5-4664-b6ff-32a936ae81a9
08:13:51.236 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix: /2/1 {11 11ä11#+} 1
08:13:51.237 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=fcfd93d7-1fe1-4f67-8197-bc8ce79b6146 remoteRepositoryId=715f1916-93d5-4664-b6ff-32a936ae81a9 localRepositoryRevisionSynced=4 localRepositoryRevisionInProgress=13
08:13:51.238 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:13:51.276 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:13:51.277 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: enlisted configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/2/.cloudstore.properties
08:13:51.277 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: enlisted configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore.properties
08:13:51.277 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - getExistingConfigFilesAbovePathPrefix: skipped non-existing configFile: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore-repo/parent.properties
08:13:51.283 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - isFileModifiedAfterLastSync: file modified: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kns44-xsyf1-local/.cloudstore.properties
08:13:51.283 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< ConfigPropSetDto[configPropDtos=[ConfigPropDto[key=ignore[test].namePattern, value=overwrittenTest], ConfigPropDto[key=ignore[blubb].namePattern, value=overwrittenBlubb], ConfigPropDto[key=ignore[bla].namePattern, value=bla], ConfigPropDto[key=ignore[oink].namePattern, value=oink!]]]
08:13:51.283 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:13:51.350 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 59.90%
08:13:51.350 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 74.63%
08:13:51.350 [main] INFO  c.c.c.t.r.PathPrefixedConfigInheritanceRepoToRepoSyncIT - Synchronising...: 100.00%
08:13:51.352 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]getCloseDeferredMillis: closeDeferredMillis=20000
08:13:51.352 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7fb8bad0]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:13:51.356 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]getCloseDeferredMillis: closeDeferredMillis=20000
08:13:51.357 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [783b3aa0]close: Deferring shut down of real LocalRepoManager 20000 ms.

Standard Error

Aug 11, 2019 8:13:45 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
Aug 11, 2019 8:13:45 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
Aug 11, 2019 8:13:46 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
Aug 11, 2019 8:13:46 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.