12583.863false011330org.subshare.core.crypto.EncrypterDecrypterStreamTest14.9520symmetricWithoutIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10303998 random bytes plaintext took 111 ms. 04:31:51.075 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 546 ms. symmetricWithoutIv: Encrypting 10303998 bytes plaintext to 10304006 bytes ciphertext took 484 ms. symmetricWithoutIv: Decrypting 10304006 bytes ciphertext to 10303998 bytes plaintext took 473 ...[truncated 37205 chars]... 7374 bytes plaintext took 293 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 4775466 random bytes plaintext took 34 ms. asymmetricCombiWithRandomIv: Encrypting 4775466 bytes plaintext to 4776010 bytes ciphertext took 259 ms. asymmetricCombiWithRandomIv: Decrypting 4776010 bytes ciphertext to 4775466 bytes plaintext took 359 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest8.5640symmetricWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10303998 random bytes plaintext took 111 ms. 04:31:51.075 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 546 ms. symmetricWithoutIv: Encrypting 10303998 bytes plaintext to 10304006 bytes ciphertext took 484 ms. symmetricWithoutIv: Decrypting 10304006 bytes ciphertext to 10303998 bytes plaintext took 473 ...[truncated 37205 chars]... 7374 bytes plaintext took 293 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 4775466 random bytes plaintext took 34 ms. asymmetricCombiWithRandomIv: Encrypting 4775466 bytes plaintext to 4776010 bytes ciphertext took 259 ms. asymmetricCombiWithRandomIv: Decrypting 4776010 bytes ciphertext to 4775466 bytes plaintext took 359 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest15.3270asymmetricPlainfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10303998 random bytes plaintext took 111 ms. 04:31:51.075 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 546 ms. symmetricWithoutIv: Encrypting 10303998 bytes plaintext to 10304006 bytes ciphertext took 484 ms. symmetricWithoutIv: Decrypting 10304006 bytes ciphertext to 10303998 bytes plaintext took 473 ...[truncated 37205 chars]... 7374 bytes plaintext took 293 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 4775466 random bytes plaintext took 34 ms. asymmetricCombiWithRandomIv: Encrypting 4775466 bytes plaintext to 4776010 bytes ciphertext took 259 ms. asymmetricCombiWithRandomIv: Decrypting 4776010 bytes ciphertext to 4775466 bytes plaintext took 359 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest14.3770asymmetricCombiWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10303998 random bytes plaintext took 111 ms. 04:31:51.075 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 546 ms. symmetricWithoutIv: Encrypting 10303998 bytes plaintext to 10304006 bytes ciphertext took 484 ms. symmetricWithoutIv: Decrypting 10304006 bytes ciphertext to 10303998 bytes plaintext took 473 ...[truncated 37205 chars]... 7374 bytes plaintext took 293 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 4775466 random bytes plaintext took 34 ms. asymmetricCombiWithRandomIv: Encrypting 4775466 bytes plaintext to 4776010 bytes ciphertext took 259 ms. asymmetricCombiWithRandomIv: Decrypting 4776010 bytes ciphertext to 4775466 bytes plaintext took 359 ms. 53.219997org.subshare.core.crypto.EncrypterDecrypterStreamTest symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10303998 random bytes plaintext took 111 ms. 04:31:51.075 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 546 ms. symmetricWithoutIv: Encrypting 10303998 bytes plaintext to 10304006 bytes ciphertext took 484 ms. symmetricWithoutIv: Decrypting 10304006 bytes ciphertext to 10303998 bytes plaintext took 473 ...[truncated 37205 chars]... 7374 bytes plaintext took 293 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 4775466 random bytes plaintext took 34 ms. asymmetricCombiWithRandomIv: Encrypting 4775466 bytes plaintext to 4776010 bytes ciphertext took 259 ms. asymmetricCombiWithRandomIv: Decrypting 4776010 bytes ciphertext to 4775466 bytes plaintext took 359 ms. 2017-09-24T02:31:500org.subshare.core.file.EncryptedDataFileTest2.6890writeReadSimpleEncryptedDataFilefalsePASSED2.689org.subshare.core.file.EncryptedDataFileTest2017-09-24T02:31:420org.subshare.core.gpg.GnuPgTest7.3940encryptSignAndDecryptVerifyfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 0org.subshare.core.gpg.GnuPgTest1.2750encryptAndDecryptfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 0org.subshare.core.gpg.GnuPgTest0.2110readPubringGpgfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 0org.subshare.core.gpg.GnuPgTest0.0190readSecringGpgfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 8.899org.subshare.core.gpg.GnuPgTest>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 2017-09-24T02:31:410org.subshare.core.io.LimitedInputStreamTest2.3880randomLengthWithUnderlyingSameLengthfalsePASSED2.388org.subshare.core.io.LimitedInputStreamTest2017-09-24T02:31:400org.subshare.core.io.MultiInputStreamTest6.0860randomCombifalsePASSED6.086org.subshare.core.io.MultiInputStreamTest2017-09-24T02:31:400org.subshare.core.pgp.PgpTest2.8820inlineSignThenEncryptAndDecryptThenVerifyfalsePASSED04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.1980signAndVerifyViaPgpSignablefalsePASSED04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest4.380inlineSignAndVerifyfalsePASSED04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.2170exportImportPublicKeyfalsePASSED04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.2860detachedSignAndVerifyfalsePASSED04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 7.9630003org.subshare.core.pgp.PgpTest04:31:47.085 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:47.119 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:47.218 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:47.219 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/TehbHmxVilJFIMAH-FWWYw/.gnupg 04:31:48.156 [Test ...[truncated 27265 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:31:56.046 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:31:480org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest3.520addAllDifferentUserRepoKeyPublicKeysToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0110addAllSameUserRepoKeyPublicKeyInCollectionToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0030addSameUserRepoKeyPublicKeyTwiceToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0040addDifferentUserRepoKeyPublicKeysToUserfalsePASSED3.5379999org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest2017-09-24T02:31:410org.subshare.core.user.UserRegistryTest5.0630addUserfalsePASSED04:31:44.412 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:44.688 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:44.909 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:44.933 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:46.042 [Test ...[truncated 13530 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 04:32:18.019 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 10190 ms. 04:32:18.077 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.391 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.547 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.user.UserRegistryTest8.1330testGpgKeyTrustLevelsfalsePASSED04:31:44.412 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:44.688 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:44.909 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:44.933 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:46.042 [Test ...[truncated 13530 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 04:32:18.019 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 10190 ms. 04:32:18.077 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.391 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.547 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.user.UserRegistryTest20.8140initUserRegistryFromGpgKeysfalsePASSED04:31:44.412 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:44.688 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:44.909 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:44.933 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:46.042 [Test ...[truncated 13530 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 04:32:18.019 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 10190 ms. 04:32:18.077 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.391 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.547 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 34.010002org.subshare.core.user.UserRegistryTest04:31:44.412 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:31:44.688 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:31:44.909 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:44.933 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LCgQKPcHCP08Rb9vDwhqdg/.gnupg 04:31:46.042 [Test ...[truncated 13530 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 04:32:18.019 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 10190 ms. 04:32:18.077 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.391 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 04:32:18.547 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:31:440org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest3.8660firstAddPublicKeysThenCreateUserRepoKeyRingfalsePASSED0org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest0.0350firstCreateUserRepoKeyRingThenAddPublicKeysfalsePASSED3.901org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest2017-09-24T02:31:4035org.subshare.crypto.AsymmetricKeyTest0.0257generateManySymmetricKeystrueSKIPPED0org.subshare.crypto.AsymmetricKeyTest31.9010encodeDecodeRSAwithOAEPwithSHA1andMGF1PaddingfalsePASSED0org.subshare.crypto.AsymmetricKeyTest34.4110encodeDecodeRSAfalsePASSED66.312org.subshare.crypto.AsymmetricKeyTest2017-09-24T02:32:570org.subshare.crypto.CipherTest3.2990testLookupAllSupportedCiphersfalsePASSED04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest11.1280testNullAsKeyParameterfalsePASSED04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.1480testLookupCompatibilityWithJCEfalsePASSED04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest5.9590testSymmetricEncryptionWithoutIvfalsePASSED04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.1560testSymmetricEncryptionCompatibilityWithJCE_BouncyCastleProviderfalsePASSED04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 20.69org.subshare.crypto.CipherTest04:33:04.947 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 3133 msec. 04:33:05.156 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 04:33:05.161 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 04:33:05.174 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 04:33:05.179 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:20.272 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 04:33:20.653 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 04:33:20.981 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 04:33:21.444 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 04:33:21.781 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 2017-09-24T02:33:010org.subshare.crypto.GCMTest12.3110testEncryptionWithCipherfalsePASSED*** 0 *** *** 1 *** *** 2 *** *** 3 *** *** 4 *** *** 5 *** *** 6 *** *** 7 *** *** 8 *** *** 9 *** *** 10 *** *** 11 *** *** 12 *** *** 13 *** *** 14 *** *** 15 *** *** 16 *** *** 17 *** *** 18 *** *** 19 *** *** 20 *** *** 21 *** *** 22 *** *** 23 *** *** 24 *** *** 25 *** *** 26 *** *** 27 *** *** 28 *** *** 29 *** *** 30 *** *** 31 *** *** 32 *** *** 33 *** *** 34 *** *** 35 *** *** 36 *** *** 37 *** *** 38 *** *** 39 *** *** 40 *** *** 41 *** *** 42 *** *** 43 *** *** 44 *** *** 45 *** *** ...[truncated 296780 chars]... 70 *** *** gcm 9971 *** *** gcm 9972 *** *** gcm 9973 *** *** gcm 9974 *** *** gcm 9975 *** *** gcm 9976 *** *** gcm 9977 *** *** gcm 9978 *** *** gcm 9979 *** *** gcm 9980 *** *** gcm 9981 *** *** gcm 9982 *** *** gcm 9983 *** *** gcm 9984 *** *** gcm 9985 *** *** gcm 9986 *** *** gcm 9987 *** *** gcm 9988 *** *** gcm 9989 *** *** gcm 9990 *** *** gcm 9991 *** *** gcm 9992 *** *** gcm 9993 *** *** gcm 9994 *** *** gcm 9995 *** *** gcm 9996 *** *** gcm 9997 *** *** gcm 9998 *** *** gcm 9999 *** 0org.subshare.crypto.GCMTest2.930testEncryptionWithBCLowLevelAPIfalsePASSED*** 0 *** *** 1 *** *** 2 *** *** 3 *** *** 4 *** *** 5 *** *** 6 *** *** 7 *** *** 8 *** *** 9 *** *** 10 *** *** 11 *** *** 12 *** *** 13 *** *** 14 *** *** 15 *** *** 16 *** *** 17 *** *** 18 *** *** 19 *** *** 20 *** *** 21 *** *** 22 *** *** 23 *** *** 24 *** *** 25 *** *** 26 *** *** 27 *** *** 28 *** *** 29 *** *** 30 *** *** 31 *** *** 32 *** *** 33 *** *** 34 *** *** 35 *** *** 36 *** *** 37 *** *** 38 *** *** 39 *** *** 40 *** *** 41 *** *** 42 *** *** 43 *** *** 44 *** *** 45 *** *** ...[truncated 296780 chars]... 70 *** *** gcm 9971 *** *** gcm 9972 *** *** gcm 9973 *** *** gcm 9974 *** *** gcm 9975 *** *** gcm 9976 *** *** gcm 9977 *** *** gcm 9978 *** *** gcm 9979 *** *** gcm 9980 *** *** gcm 9981 *** *** gcm 9982 *** *** gcm 9983 *** *** gcm 9984 *** *** gcm 9985 *** *** gcm 9986 *** *** gcm 9987 *** *** gcm 9988 *** *** gcm 9989 *** *** gcm 9990 *** *** gcm 9991 *** *** gcm 9992 *** *** gcm 9993 *** *** gcm 9994 *** *** gcm 9995 *** *** gcm 9996 *** *** gcm 9997 *** *** gcm 9998 *** *** gcm 9999 *** 15.241org.subshare.crypto.GCMTest*** 0 *** *** 1 *** *** 2 *** *** 3 *** *** 4 *** *** 5 *** *** 6 *** *** 7 *** *** 8 *** *** 9 *** *** 10 *** *** 11 *** *** 12 *** *** 13 *** *** 14 *** *** 15 *** *** 16 *** *** 17 *** *** 18 *** *** 19 *** *** 20 *** *** 21 *** *** 22 *** *** 23 *** *** 24 *** *** 25 *** *** 26 *** *** 27 *** *** 28 *** *** 29 *** *** 30 *** *** 31 *** *** 32 *** *** 33 *** *** 34 *** *** 35 *** *** 36 *** *** 37 *** *** 38 *** *** 39 *** *** 40 *** *** 41 *** *** 42 *** *** 43 *** *** 44 *** *** 45 *** *** ...[truncated 296780 chars]... 70 *** *** gcm 9971 *** *** gcm 9972 *** *** gcm 9973 *** *** gcm 9974 *** *** gcm 9975 *** *** gcm 9976 *** *** gcm 9977 *** *** gcm 9978 *** *** gcm 9979 *** *** gcm 9980 *** *** gcm 9981 *** *** gcm 9982 *** *** gcm 9983 *** *** gcm 9984 *** *** gcm 9985 *** *** gcm 9986 *** *** gcm 9987 *** *** gcm 9988 *** *** gcm 9989 *** *** gcm 9990 *** *** gcm 9991 *** *** gcm 9992 *** *** gcm 9993 *** *** gcm 9994 *** *** gcm 9995 *** *** gcm 9996 *** *** gcm 9997 *** *** gcm 9998 *** *** gcm 9999 *** 2017-09-24T02:32:560org.subshare.crypto.MACBenchmark0.160testByteOrderfalsePASSED04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 0org.subshare.crypto.MACBenchmark4.9720benchmarkCreateManyInitialisedMACCalculatorsfalsePASSED04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 0org.subshare.crypto.MACBenchmark0.1560benchmarkCreateManyUNinitialisedMACCalculatorsfalsePASSED04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 0org.subshare.crypto.MACBenchmark24.0130benchmarkCalculateManyMACsWithNewMACCalculatorfalsePASSED04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 0org.subshare.crypto.MACBenchmark14.3610benchmarkCalculateManyMACsWithSameMACCalculatorfalsePASSED04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 43.662003org.subshare.crypto.MACBenchmark04:33:01.390 [Test worker] INFO o.s.c.MACBenchmark - short1 = 4083 04:33:01.441 [Test worker] INFO o.s.c.MACBenchmark - short2 = 4083 04:33:01.450 [Test worker] INFO o.s.c.MACBenchmark - int1 = 4083 2017-09-24T02:33:010org.subshare.crypto.MACTest5.0790testAllSupportedMACsfalsePASSED5.079org.subshare.crypto.MACTest2017-09-24T02:32:590org.subshare.crypto.ShowSupportedAlgosForDocumentation0.290showSupportedBlockCipherModesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** BlockCipherMode *** CBC CBC-CTS CCM CFB CFB104 CFB112 CFB120 CFB128 CFB136 CFB144 CFB152 CFB16 CFB160 CFB168 CFB176 CFB184 CFB192 CFB200 CFB208 CFB216 CFB224 CFB232 CFB24 CFB240 CFB248 CFB256 CFB32 CFB40 CFB48 CFB56 CFB64 CFB72 CFB8 CFB80 CFB88 CFB96 CTS EAX ECB GCM GOFB OFB OFB104 OFB112 OFB120 OFB128 OFB136 OFB144 OFB152 OFB16 OFB160 OFB168 OFB176 OFB184 OFB192 OFB200 OFB208 OFB216 OFB224 OFB232 OFB24 OFB240 OFB248 OFB256 OFB32 OFB40 OFB48 OFB56 OFB64 ...[truncated 1515 chars]... ADDING NOPADDING PKCS5 PKCS5PADDING PKCS7 PKCS7PADDING TBC TBCPADDING X9.23 X9.23PADDING ZEROBYTE ZEROBYTEPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/asymmetricBlock *** ISO9796-1 ISO9796-1PADDING NOPADDING OAEP OAEPPADDING OAEPWITHSHA1ANDMGF1 OAEPWITHSHA1ANDMGF1PADDING PKCS1 PKCS1PADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/symmetricStream *** NOPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 0org.subshare.crypto.ShowSupportedAlgosForDocumentation0.1650showSupportedCipherEnginesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** BlockCipherMode *** CBC CBC-CTS CCM CFB CFB104 CFB112 CFB120 CFB128 CFB136 CFB144 CFB152 CFB16 CFB160 CFB168 CFB176 CFB184 CFB192 CFB200 CFB208 CFB216 CFB224 CFB232 CFB24 CFB240 CFB248 CFB256 CFB32 CFB40 CFB48 CFB56 CFB64 CFB72 CFB8 CFB80 CFB88 CFB96 CTS EAX ECB GCM GOFB OFB OFB104 OFB112 OFB120 OFB128 OFB136 OFB144 OFB152 OFB16 OFB160 OFB168 OFB176 OFB184 OFB192 OFB200 OFB208 OFB216 OFB224 OFB232 OFB24 OFB240 OFB248 OFB256 OFB32 OFB40 OFB48 OFB56 OFB64 ...[truncated 1515 chars]... ADDING NOPADDING PKCS5 PKCS5PADDING PKCS7 PKCS7PADDING TBC TBCPADDING X9.23 X9.23PADDING ZEROBYTE ZEROBYTEPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/asymmetricBlock *** ISO9796-1 ISO9796-1PADDING NOPADDING OAEP OAEPPADDING OAEPWITHSHA1ANDMGF1 OAEPWITHSHA1ANDMGF1PADDING PKCS1 PKCS1PADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/symmetricStream *** NOPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 0org.subshare.crypto.ShowSupportedAlgosForDocumentation0.1350showSupportedMACsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** BlockCipherMode *** CBC CBC-CTS CCM CFB CFB104 CFB112 CFB120 CFB128 CFB136 CFB144 CFB152 CFB16 CFB160 CFB168 CFB176 CFB184 CFB192 CFB200 CFB208 CFB216 CFB224 CFB232 CFB24 CFB240 CFB248 CFB256 CFB32 CFB40 CFB48 CFB56 CFB64 CFB72 CFB8 CFB80 CFB88 CFB96 CTS EAX ECB GCM GOFB OFB OFB104 OFB112 OFB120 OFB128 OFB136 OFB144 OFB152 OFB16 OFB160 OFB168 OFB176 OFB184 OFB192 OFB200 OFB208 OFB216 OFB224 OFB232 OFB24 OFB240 OFB248 OFB256 OFB32 OFB40 OFB48 OFB56 OFB64 ...[truncated 1515 chars]... ADDING NOPADDING PKCS5 PKCS5PADDING PKCS7 PKCS7PADDING TBC TBCPADDING X9.23 X9.23PADDING ZEROBYTE ZEROBYTEPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/asymmetricBlock *** ISO9796-1 ISO9796-1PADDING NOPADDING OAEP OAEPPADDING OAEPWITHSHA1ANDMGF1 OAEPWITHSHA1ANDMGF1PADDING PKCS1 PKCS1PADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/symmetricStream *** NOPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 0org.subshare.crypto.ShowSupportedAlgosForDocumentation0.0940showSupportedPaddingsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** BlockCipherMode *** CBC CBC-CTS CCM CFB CFB104 CFB112 CFB120 CFB128 CFB136 CFB144 CFB152 CFB16 CFB160 CFB168 CFB176 CFB184 CFB192 CFB200 CFB208 CFB216 CFB224 CFB232 CFB24 CFB240 CFB248 CFB256 CFB32 CFB40 CFB48 CFB56 CFB64 CFB72 CFB8 CFB80 CFB88 CFB96 CTS EAX ECB GCM GOFB OFB OFB104 OFB112 OFB120 OFB128 OFB136 OFB144 OFB152 OFB16 OFB160 OFB168 OFB176 OFB184 OFB192 OFB200 OFB208 OFB216 OFB224 OFB232 OFB24 OFB240 OFB248 OFB256 OFB32 OFB40 OFB48 OFB56 OFB64 ...[truncated 1515 chars]... ADDING NOPADDING PKCS5 PKCS5PADDING PKCS7 PKCS7PADDING TBC TBCPADDING X9.23 X9.23PADDING ZEROBYTE ZEROBYTEPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/asymmetricBlock *** ISO9796-1 ISO9796-1PADDING NOPADDING OAEP OAEPPADDING OAEPWITHSHA1ANDMGF1 OAEPWITHSHA1ANDMGF1PADDING PKCS1 PKCS1PADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/symmetricStream *** NOPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 0.68399996org.subshare.crypto.ShowSupportedAlgosForDocumentation>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** BlockCipherMode *** CBC CBC-CTS CCM CFB CFB104 CFB112 CFB120 CFB128 CFB136 CFB144 CFB152 CFB16 CFB160 CFB168 CFB176 CFB184 CFB192 CFB200 CFB208 CFB216 CFB224 CFB232 CFB24 CFB240 CFB248 CFB256 CFB32 CFB40 CFB48 CFB56 CFB64 CFB72 CFB8 CFB80 CFB88 CFB96 CTS EAX ECB GCM GOFB OFB OFB104 OFB112 OFB120 OFB128 OFB136 OFB144 OFB152 OFB16 OFB160 OFB168 OFB176 OFB184 OFB192 OFB200 OFB208 OFB216 OFB224 OFB232 OFB24 OFB240 OFB248 OFB256 OFB32 OFB40 OFB48 OFB56 OFB64 ...[truncated 1515 chars]... ADDING NOPADDING PKCS5 PKCS5PADDING PKCS7 PKCS7PADDING TBC TBCPADDING X9.23 X9.23PADDING ZEROBYTE ZEROBYTEPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/asymmetricBlock *** ISO9796-1 ISO9796-1PADDING NOPADDING OAEP OAEPPADDING OAEPWITHSHA1ANDMGF1 OAEPWITHSHA1ANDMGF1PADDING PKCS1 PKCS1PADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** Padding/symmetricStream *** NOPADDING <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 2017-09-24T02:33:010org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest93.830grantAndRevokeReadPermissionfalsePASSED04:34:35.257 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.667 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.745 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [70a393d5]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6my-aubol-local' 04:34:37.865 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1136 ms. 04 ...[truncated 40232 chars]... NFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 04:36:08.775 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 04:36:08.856 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 04:36:09.223 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [70a393d5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:36:09.228 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 93.83org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest04:34:35.257 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.667 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.745 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [70a393d5]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6my-aubol-local' 04:34:37.865 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1136 ms. 04 ...[truncated 40232 chars]... NFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 04:36:08.775 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 04:36:08.856 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 04:36:09.223 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [70a393d5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:36:09.228 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:34:350org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest84.0590grantWritePermissionWithoutGrantPermissionfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest35.1190writeWithWritePermissionInParentParentfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest32.930writeWithWritePermissionInParentfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest35.4530writeWithWritePermissionDirectDirfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest34.1350writeWithoutWritePermissionfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest35.0090writeWithWritePermissionInParentParentGrantedIndirectlyfalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest29.9520writeWithWritePermissionDirectFilefalsePASSED04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 286.65698org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest04:34:35.450 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:35.886 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:37.626 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [48470dc7]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j7y4s6l0-9lqrr-local' 04:34:37.824 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1176 ms. 04 ...[truncated 197616 chars]... Test - Local sync...: 79.60% 04:39:22.208 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 04:39:22.227 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 04:39:22.243 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 04:39:22.281 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7dca8753]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:39:22.283 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:34:350org.subshare.local.FilePaddingLengthRandomTest14.7120testDistributionfalsePASSED04:34:36.803 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300366, _1M=147647, _10M=46979, _100M=2991, _1G=2017, _10G=0} lengthCategory2HitPercentage: {_100K=601, _1M=295, _10M=94, _100M=6, _1G=4, _10G=0} 38176 0org.subshare.local.FilePaddingLengthRandomTest0.0320generateOneRandomLengthfalsePASSED04:34:36.803 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300366, _1M=147647, _10M=46979, _100M=2991, _1G=2017, _10G=0} lengthCategory2HitPercentage: {_100K=601, _1M=295, _10M=94, _100M=6, _1G=4, _10G=0} 38176 14.7439995org.subshare.local.FilePaddingLengthRandomTest04:34:36.803 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300366, _1M=147647, _10M=46979, _100M=2991, _1G=2017, _10G=0} lengthCategory2HitPercentage: {_100K=601, _1M=295, _10M=94, _100M=6, _1G=4, _10G=0} 38176 2017-09-24T02:34:320org.subshare.local.persistence.CollisionTest4.4810xorUids1falsePASSED0org.subshare.local.persistence.CollisionTest0.0020xorUids2falsePASSED4.483org.subshare.local.persistence.CollisionTest2017-09-24T02:34:310org.subshare.local.sign.SignableTest2.6520signAndSerializeAndVerifyfalsePASSED04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1430signAndModifyCryptoKeyIdAndVerifyfalsePASSED04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.120signAndVerifyfalsePASSED04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.090signAndModifySignatureCreatedAndVerifyfalsePASSED04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1110signAndModifyCryptoRepoFileIdAndVerifyfalsePASSED04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 3.116org.subshare.local.sign.SignableTest04:34:35.711 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.593 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.671 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1329 ms. 04:34:39.061 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.062 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.147 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.210 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.248 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.303 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.345 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:34:44.412 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:34:410org.subshare.local.sign.SignerVerifierStreamTest19.6930signRsaWithSha1falsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest0.1160signAndVerifySimpleBlockReadfalsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest21.3140sha1DigestfalsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest2.8360signAndVerifyRandomBrokenfalsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest13.4940signAndVerifyRandomManySmallfalsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest3.2370signAndVerifyRandomfalsePASSED04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 60.69org.subshare.local.sign.SignerVerifierStreamTest04:34:36.167 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:34:37.329 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:34:37.504 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1496 ms. 04:34:39.079 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 04:34:39.098 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 04 ...[truncated 5060 chars]... all run #93 signAndVerifyRandomManySmall run #94 signAndVerifyRandomManySmall run #95 signAndVerifyRandomManySmall run #96 signAndVerifyRandomManySmall run #97 signAndVerifyRandomManySmall run #98 signAndVerifyRandomManySmall run #99 signAndVerifyRandomManySmall run #100 04:35:38.964 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:38.995 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:35:42.202 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:34:410org.subshare.rest.client.transport.CryptreeRepoTransportTest126.5660encryptAndSignAndVerifyAndDecryptfalsePASSED04:39:28.527 [Test worker] INFO c.c.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:39:29.110 [Test worker] DEBUG o.s.c.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:39:29.126 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 04:39:29.127 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - read: Reading propertie ...[truncated 44322 chars]... y='subshare.signerTransformation' is not set (config is queried next). 04:41:34.188 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 04:41:34.640 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 04:41:34.640 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 126.566org.subshare.rest.client.transport.CryptreeRepoTransportTest04:39:28.527 [Test worker] INFO c.c.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 04:39:29.110 [Test worker] DEBUG o.s.c.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 04:39:29.126 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 04:39:29.127 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - read: Reading propertie ...[truncated 44322 chars]... y='subshare.signerTransformation' is not set (config is queried next). 04:41:34.188 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 04:41:34.640 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 04:41:34.640 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 2017-09-24T02:39:280org.subshare.test.BackupRestoreRepoToRepoSyncIT322.5260backupRestoreClientRepofalsePASSED05:13:12.855 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:13:13.682 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' 05:13:13.686 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' locke ...[truncated 313464 chars]... saction.localRevision=152 remoteFromLocalRevision=141 05:18:34.815 [qtp298852037-2892] INFO c.c.c.l.LocalRepoManagerImpl - [74d70c58]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:34.817 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:18:34.817 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1ec61b48]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:35.372 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1org.subshare.test.BackupRestoreRepoToRepoSyncIT0.0291backupRestoreServerRepotrueSKIPPED05:13:12.855 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:13:13.682 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' 05:13:13.686 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' locke ...[truncated 313464 chars]... saction.localRevision=152 remoteFromLocalRevision=141 05:18:34.815 [qtp298852037-2892] INFO c.c.c.l.LocalRepoManagerImpl - [74d70c58]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:34.817 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:18:34.817 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1ec61b48]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:35.372 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 322.526org.subshare.test.BackupRestoreRepoToRepoSyncIT05:13:12.855 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:13:13.682 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' 05:13:13.686 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [331a36d1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y65tq9-qktz1-local-src' locke ...[truncated 313464 chars]... saction.localRevision=152 remoteFromLocalRevision=141 05:18:34.815 [qtp298852037-2892] INFO c.c.c.l.LocalRepoManagerImpl - [74d70c58]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:34.817 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:18:34.817 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1ec61b48]close: Deferring shut down of real LocalRepoManager 10000 ms. 05:18:35.372 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T03:13:120org.subshare.test.BrokenSignatureIT222.3490uploadBrokenSignaturefalsePASSEDSep 24, 2017 4:42:15 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:19 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.200 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.119 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/.subshare/ssl.server/keystore' does not exist! 04:41:55.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 469139 chars]... ncurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 0org.subshare.test.BrokenSignatureIT149.4520downloadBrokenSignaturefalsePASSEDSep 24, 2017 4:42:15 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:19 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.200 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.119 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/.subshare/ssl.server/keystore' does not exist! 04:41:55.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 469139 chars]... ncurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 371.801org.subshare.test.BrokenSignatureITSep 24, 2017 4:42:15 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:19 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.200 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.119 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/.subshare/ssl.server/keystore' does not exist! 04:41:55.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 469139 chars]... ncurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 2017-09-24T02:42:210org.subshare.test.CollisionOnClientRepoToRepoSyncIT262.020newFileVsNewFileCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT253.6710newDirectoryVsNewDirectoryCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT235.1120modifiedFileVsDeletedFileCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT245.1380modifiedFileVsModifiedFileCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT240.0640deletedFileVsModifiedFileCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT159.3330newFileVsNewDirectoryCollisionOnClientfalsePASSED05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1395.338org.subshare.test.CollisionOnClientRepoToRepoSyncIT05:00:21.383 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:00:22.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' 05:00:22.107 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3e5e2e01]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5pads-luysl-local-src' locke ...[truncated 1736727 chars]... ons: query.execute(...) took 4 ms. 05:23:36.381 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f160170]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.382 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [75da4fc5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:23:36.726 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T03:00:210org.subshare.test.CollisionOnServerRepoToRepoSyncIT408.830newFileVsNewFileUploadedCollisionOnServerfalsePASSEDSep 24, 2017 4:42:25 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:29 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.546 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.416 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/R4XbzUHyJt68wdYRXeAARA/.subshare/ssl.server/keystore' does not exist! 04:41:56.440 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 949414 chars]... ions: query.execute(...) took 6 ms. 05:02:18.262 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 1 ms. 05:02:18.263 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1dd3c1a6]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.264 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [87eb754]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.589 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnServerRepoToRepoSyncIT778.0420newFileVsNewFileUploadingCollisionOnServerfalsePASSEDSep 24, 2017 4:42:25 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:29 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.546 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.416 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/R4XbzUHyJt68wdYRXeAARA/.subshare/ssl.server/keystore' does not exist! 04:41:56.440 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 949414 chars]... ions: query.execute(...) took 6 ms. 05:02:18.262 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 1 ms. 05:02:18.263 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1dd3c1a6]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.264 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [87eb754]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.589 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1186.872org.subshare.test.CollisionOnServerRepoToRepoSyncITSep 24, 2017 4:42:25 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:29 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.546 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.416 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/R4XbzUHyJt68wdYRXeAARA/.subshare/ssl.server/keystore' does not exist! 04:41:56.440 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 949414 chars]... ions: query.execute(...) took 6 ms. 05:02:18.262 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 1 ms. 05:02:18.263 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1dd3c1a6]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.264 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [87eb754]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:02:18.589 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:42:310org.subshare.test.IgnoreRuleRepoToRepoSyncIT347.8030ignoreRulesAddedAfterFilesSyncedfalsePASSEDSep 24, 2017 4:42:09 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:12 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.403 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.798 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/TLPE_H-eRlrXw_ttSl_85Q/.subshare/ssl.server/keystore' does not exist! 04:41:55.811 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 914867 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.127 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5eaba3d8]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [14f8f4f6]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.463 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT330.4840ignoreRulesBecomeDisabledfalsePASSEDSep 24, 2017 4:42:09 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:12 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.403 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.798 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/TLPE_H-eRlrXw_ttSl_85Q/.subshare/ssl.server/keystore' does not exist! 04:41:55.811 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 914867 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.127 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5eaba3d8]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [14f8f4f6]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.463 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT340.0190ignoreRulesExistBeforeAffectedFilesfalsePASSEDSep 24, 2017 4:42:09 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:12 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.403 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.798 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/TLPE_H-eRlrXw_ttSl_85Q/.subshare/ssl.server/keystore' does not exist! 04:41:55.811 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 914867 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.127 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5eaba3d8]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [14f8f4f6]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.463 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1018.306org.subshare.test.IgnoreRuleRepoToRepoSyncITSep 24, 2017 4:42:09 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:12 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.403 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.798 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/TLPE_H-eRlrXw_ttSl_85Q/.subshare/ssl.server/keystore' does not exist! 04:41:55.811 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 914867 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.127 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5eaba3d8]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.128 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [14f8f4f6]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:59:13.463 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:42:150org.subshare.test.InterruptedRepoToRepoSyncIT245.1510interruptSyncDownAndResumefalsePASSED04:48:33.188 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:48:33.804 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' 04:48:33.813 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' locke ...[truncated 432944 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.617 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:58:09.668 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4de252ee]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.669 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6e149d3f]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:10.011 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InterruptedRepoToRepoSyncIT331.6770interruptSyncUpAndResumefalsePASSED04:48:33.188 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:48:33.804 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' 04:48:33.813 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' locke ...[truncated 432944 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.617 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:58:09.668 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4de252ee]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.669 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6e149d3f]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:10.011 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 576.828org.subshare.test.InterruptedRepoToRepoSyncIT04:48:33.188 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:48:33.804 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' 04:48:33.813 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5be950df]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5a3uf-77lkg-local-src' locke ...[truncated 432944 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.617 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:58:09.668 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4de252ee]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:09.669 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6e149d3f]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:58:10.011 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:48:330org.subshare.test.InviteUserAndSyncIT156.9440inviteUserAndSync_singleReadPermissionOnSubdirfalsePASSED05:23:37.073 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:23:37.074 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:23:37.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 936032 chars]... <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 05:29:51.282 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 05:29:51.282 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1b63e0d4]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.283 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ac08209]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.625 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InviteUserAndSyncIT109.0040inviteUserAndSync_singleWritePermissionOnRootfalsePASSED05:23:37.073 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:23:37.074 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:23:37.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 936032 chars]... <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 05:29:51.282 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 05:29:51.282 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1b63e0d4]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.283 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ac08209]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.625 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InviteUserAndSyncIT108.6040inviteUserAndSync_singleReadPermissionOnRootfalsePASSED05:23:37.073 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:23:37.074 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:23:37.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 936032 chars]... <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 05:29:51.282 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 05:29:51.282 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1b63e0d4]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.283 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ac08209]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.625 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 374.552org.subshare.test.InviteUserAndSyncIT05:23:37.073 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:23:37.074 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:23:37.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:23:37.398 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 936032 chars]... <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 05:29:51.282 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 05:29:51.282 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1b63e0d4]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.283 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ac08209]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:29:51.625 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T03:23:370org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT377.1450inviteUserAndSync_withMetaOnly_singleWritePermissionOnRootfalsePASSED04:49:58.794 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 04:49:58.795 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 04:49:59.251 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:49:59.254 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:49:59.254 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 384406 chars]... ly_singleWritePermissionOnRoot <<< *** 04:56:15.587 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 04:56:15.587 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [45d9cf60]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:15.588 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36069af5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:15.933 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 377.145org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT04:49:58.794 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 04:49:58.795 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 04:49:59.251 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:49:59.254 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:49:59.254 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 384406 chars]... ly_singleWritePermissionOnRoot <<< *** 04:56:15.587 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 04:56:15.587 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [45d9cf60]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:15.588 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36069af5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:15.933 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:49:580org.subshare.test.Issue4IT236.9620issue_4_syncFileDeletedAfterLocalSyncfalsePASSED04:56:23.750 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:25.030 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k7gc-q709w-local-src' 04:56:25.042 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k7gc-q709w-local-src' locke ...[truncated 149151 chars]... nsaction.localRevision=97 remoteFromLocalRevision=149 05:00:20.372 [qtp178185341-1702] INFO c.c.c.l.LocalRepoManagerImpl - [2c12bef1]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:00:20.384 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:00:20.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:00:20.709 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 236.962org.subshare.test.Issue4IT04:56:23.750 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:25.030 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k7gc-q709w-local-src' 04:56:25.042 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k7gc-q709w-local-src' locke ...[truncated 149151 chars]... nsaction.localRevision=97 remoteFromLocalRevision=149 05:00:20.372 [qtp178185341-1702] INFO c.c.c.l.LocalRepoManagerImpl - [2c12bef1]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:00:20.384 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:00:20.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54eeb8ab]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:00:20.709 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:56:230org.subshare.test.Issue5IT446.7810issue_5_inviteMultipleFriendsfalsePASSEDSep 24, 2017 4:42:24 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:28 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.822 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.596 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/iLkfJZuWYh0XVufEfHAb7w/.subshare/ssl.server/keystore' does not exist! 04:41:56.599 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 457712 chars]... sing...: 100.00% 04:49:58.194 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6bbdd5ce]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.203 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [e2d7598]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.211 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1ab080ad]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.752 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 446.781org.subshare.test.Issue5ITSep 24, 2017 4:42:24 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:28 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.822 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.596 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/iLkfJZuWYh0XVufEfHAb7w/.subshare/ssl.server/keystore' does not exist! 04:41:56.599 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 457712 chars]... sing...: 100.00% 04:49:58.194 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6bbdd5ce]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.203 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [e2d7598]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.211 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1ab080ad]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:49:58.752 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:42:310org.subshare.test.Issue8IT653.540issue_8_threesomeWithFreshmenfalsePASSED 05:02:24.703 [Test worker] INFO o.s.t.AbstractMultiUserIT - 05:02:24.708 [Test worker] INFO o.s.t.AbstractMultiUserIT - ******************************************************************** 05:02:24.708 [Test worker] INFO o.s.t.AbstractMultiUserIT - *** marco *** 05:02:24.717 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:02:24.718 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<GnuPgDir>.getInstance: testUser=marco 05:02:24.719 [Test worker] INFO o.s.t.PgpTestU ...[truncated 1202754 chars]... nager 20000 ms. 05:13:09.579 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<ServerRepoRegistryImpl>.getInstance: testUser=xenia 05:13:09.579 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 05:13:09.615 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 05:13:09.616 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4c877843]close: Deferring shut down of real LocalRepoManager 20000 ms. 653.54org.subshare.test.Issue8IT 05:02:24.703 [Test worker] INFO o.s.t.AbstractMultiUserIT - 05:02:24.708 [Test worker] INFO o.s.t.AbstractMultiUserIT - ******************************************************************** 05:02:24.708 [Test worker] INFO o.s.t.AbstractMultiUserIT - *** marco *** 05:02:24.717 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:02:24.718 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<GnuPgDir>.getInstance: testUser=marco 05:02:24.719 [Test worker] INFO o.s.t.PgpTestU ...[truncated 1202754 chars]... nager 20000 ms. 05:13:09.579 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<ServerRepoRegistryImpl>.getInstance: testUser=xenia 05:13:09.579 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 05:13:09.615 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 05:13:09.616 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4c877843]close: Deferring shut down of real LocalRepoManager 20000 ms. 2017-09-24T03:02:190org.subshare.test.LocalServerClientIT2.6670invokeSimpleStaticMethodfalsePASSEDSep 24, 2017 4:42:38 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:43 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.708 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.622 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/j1UyZDXawTOOeVKJ0EOxUA/.subshare/ssl.server/keystore' does not exist! 04:41:56.632 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75975 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:42:55.945 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.LocalServerClientIT5.7550invokeDeniedMethodsfalsePASSEDSep 24, 2017 4:42:38 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:43 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.708 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.622 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/j1UyZDXawTOOeVKJ0EOxUA/.subshare/ssl.server/keystore' does not exist! 04:41:56.632 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75975 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:42:55.945 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 8.422org.subshare.test.LocalServerClientITSep 24, 2017 4:42:38 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:43 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:52.708 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:56.622 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/j1UyZDXawTOOeVKJ0EOxUA/.subshare/ssl.server/keystore' does not exist! 04:41:56.632 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75975 chars]... :54) [gradle-base-services-3.1.jar:3.1] at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:40) [gradle-base-services-3.1.jar:3.1] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_131] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_131] 04:42:55.945 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:42:470org.subshare.test.LockerSyncIT19.2430syncServerRegistryfalsePASSED04:58:13.892 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:58:13.900 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/client1/.gnupg/pubring.gpg' 04:58:13.901 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 170437 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='SUhStlgAu9gJtaKceYzo7g' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 04:58:44.145 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 04:58:44.148 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 04:58:44.249 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 0org.subshare.test.LockerSyncIT16.7220syncUserRegistryfalsePASSED04:58:13.892 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:58:13.900 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/client1/.gnupg/pubring.gpg' 04:58:13.901 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 170437 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='SUhStlgAu9gJtaKceYzo7g' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 04:58:44.145 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 04:58:44.148 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 04:58:44.249 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 35.965org.subshare.test.LockerSyncIT04:58:13.892 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:58:13.900 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/-3O0RDBWkcFY4YJ8ilvhaQ/client1/.gnupg/pubring.gpg' 04:58:13.901 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 170437 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='SUhStlgAu9gJtaKceYzo7g' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 04:58:44.145 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 04:58:44.148 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 04:58:44.249 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 2017-09-24T02:58:100org.subshare.test.MetaOnlyRepoSyncIT315.8180syncFromLocalToRemoteToMetaOnlyAddingFilesfalsePASSEDSep 24, 2017 4:42:40 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:45 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.995 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.758 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_4Gbm3D28IaDNK3OaBLKTg/.subshare/ssl.server/keystore' does not exist! 04:41:55.763 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 580745 chars]... o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 04:56:23.122 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [27b80ec4]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2711b525]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.443 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.MetaOnlyRepoSyncIT257.3370syncFromLocalToRemoteToMetaOnlyfalsePASSEDSep 24, 2017 4:42:40 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:45 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.995 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.758 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_4Gbm3D28IaDNK3OaBLKTg/.subshare/ssl.server/keystore' does not exist! 04:41:55.763 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 580745 chars]... o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 04:56:23.122 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [27b80ec4]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2711b525]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.443 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.MetaOnlyRepoSyncIT243.1360syncFromLocalToRemoteToMetaOnlyRemovingFilesfalsePASSEDSep 24, 2017 4:42:40 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:45 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.995 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.758 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_4Gbm3D28IaDNK3OaBLKTg/.subshare/ssl.server/keystore' does not exist! 04:41:55.763 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 580745 chars]... o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 04:56:23.122 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [27b80ec4]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2711b525]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.443 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 816.291org.subshare.test.MetaOnlyRepoSyncITSep 24, 2017 4:42:40 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Sep 24, 2017 4:42:45 AM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 04:41:51.995 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 04:41:55.758 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_4Gbm3D28IaDNK3OaBLKTg/.subshare/ssl.server/keystore' does not exist! 04:41:55.763 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 580745 chars]... o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 04:56:23.122 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [27b80ec4]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.132 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2711b525]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:56:23.443 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:42:470org.subshare.test.MultiplePgpKeysSingleUserRegistryIT4.6680multiplePgpKeysSingleUserRegistryfalsePASSED04:50:08.829 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:50:08.833 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/j1UyZDXawTOOeVKJ0EOxUA/.gnupg/pubring.gpg' 04:50:08.835 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org ...[truncated 2805 chars]... pair with publicExponent='65537' strength='1024' certainty='12' took 124 ms. 04:50:12.627 [Test worker] DEBUG o.s.c.u.UserImpl - createUserRepoKey: pgpKey=PgpKey[bbef20af171556db, [Alexander Bieber (TEST) <alex@nightlabs.de>]], userRepoKey=UserRepoKeyImpl[userRepoKeyId=ECqPazzGsCTtHiChXdjTqQ, invitation=false] 04:50:12.667 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 04:50:13.021 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 4.668org.subshare.test.MultiplePgpKeysSingleUserRegistryIT04:50:08.829 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 04:50:08.833 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/j1UyZDXawTOOeVKJ0EOxUA/.gnupg/pubring.gpg' 04:50:08.835 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org ...[truncated 2805 chars]... pair with publicExponent='65537' strength='1024' certainty='12' took 124 ms. 04:50:12.627 [Test worker] DEBUG o.s.c.u.UserImpl - createUserRepoKey: pgpKey=PgpKey[bbef20af171556db, [Alexander Bieber (TEST) <alex@nightlabs.de>]], userRepoKey=UserRepoKeyImpl[userRepoKeyId=ECqPazzGsCTtHiChXdjTqQ, invitation=false] 04:50:12.667 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 04:50:13.021 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:50:080org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT423.6650parentConfigWithRemotePathPrefixfalsePASSED04:43:04.363 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:43:04.863 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y531zk-8wpmr-local-src' 04:43:04.892 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y531zk-8wpmr-local-src' locke ...[truncated 489234 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:07.642 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:50:07.674 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:07.677 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3bdbace5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:08.026 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 423.665org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT04:43:04.363 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:43:04.863 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y531zk-8wpmr-local-src' 04:43:04.892 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y531zk-8wpmr-local-src' locke ...[truncated 489234 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:07.642 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 04:50:07.674 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [335421d1]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:07.677 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3bdbace5]close: Deferring shut down of real LocalRepoManager 20000 ms. 04:50:08.026 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:43:040org.subshare.test.PermissionIT377.9240interruptPermissionSetInheritancefalsePASSED04:59:13.825 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:59:14.485 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' 04:59:14.492 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' locke ...[truncated 1275554 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.048 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:17:28.082 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [55b0aa76]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.083 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1bf6ab27]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.531 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.PermissionIT315.1520uploadBackdatedSignaturefalsePASSED04:59:13.825 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:59:14.485 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' 04:59:14.492 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' locke ...[truncated 1275554 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.048 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:17:28.082 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [55b0aa76]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.083 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1bf6ab27]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.531 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.PermissionIT401.6280nonOwnerAdminGrantsWritePermissionfalsePASSED04:59:13.825 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:59:14.485 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' 04:59:14.492 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' locke ...[truncated 1275554 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.048 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:17:28.082 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [55b0aa76]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.083 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1bf6ab27]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.531 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1094.7041org.subshare.test.PermissionIT04:59:13.825 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:59:14.485 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' 04:59:14.492 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [63a870c4]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5nu7k-vd3q5-local-src' locke ...[truncated 1275554 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.048 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:17:28.082 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [55b0aa76]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.083 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1bf6ab27]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:17:28.531 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:59:130org.subshare.test.PgpSyncIT2.6860testPgpSyncfalsePASSED05:26:07.873 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:26:10.556 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2.686org.subshare.test.PgpSyncIT05:26:07.873 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:26:10.556 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T03:26:070org.subshare.test.ReadUserIdentityIT270.3350inviteWithReadInvitationAndGrantReadUserIdentityPermissionLaterfalsePASSED05:17:28.994 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:17:28.995 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:17:29.422 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:17:29.430 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:17:29.437 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 430934 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:58.487 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:21:58.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [19f28bd5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:58.946 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f30b4ca]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:59.321 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 270.335org.subshare.test.ReadUserIdentityIT05:17:28.994 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 05:17:28.995 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 05:17:29.422 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 05:17:29.430 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 05:17:29.437 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 430934 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:58.487 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:21:58.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [19f28bd5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:58.946 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7f30b4ca]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:21:59.321 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T03:17:280org.subshare.test.RepoToRepoSyncIT360.3370syncFromLocalToRemoteToLocalWithPathPrefixWithWritePermissionGrantedAndRevokedfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT224.4010syncFromLocalToRemoteToLocalfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT228.4180syncFromLocalToRemoteToLocalWithPathPrefixWithoutSubdirClearanceKeyfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT237.3810syncFromLocalToRemoteToLocalWithPathPrefixfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT238.7780syncFromLocalToRemoteToLocalThenDeleteFileAndSyncAgainfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT332.9080syncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 35org.subshare.test.RepoToRepoSyncIT0.0257syncFromLocalToRemoteToLocalThenCauseDeleteCollisionOnServerDuringUpSynctrueSKIPPED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT167.1570multiSyncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1789.3799org.subshare.test.RepoToRepoSyncIT04:56:15.975 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:56:17.039 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' 04:56:17.045 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ee48407]initLockFile: Repository '/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/repo/j7y5k1ak-yimad-local-src' locke ...[truncated 2068855 chars]... ose: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.910 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 05:26:04.959 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7afe6d1a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:04.969 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [15c3d85a]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:26:05.375 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:56:150org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT312.6490newFileVsNewSymlinkCollisionOnClientfalsePASSED04:58:46.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:58:46.779 [Timer-3] WARN c.c.c.r.c.CloudStoreRestClient - execute: invocation failed (will NOT retry): javax.ws.rs.ProcessingException: java.net.UnknownHostException: server3.oink.org javax.ws.rs.ProcessingException: java.net.UnknownHostException: server3.oink.org at org.glassfish.jersey.client.HttpUrlConnector.apply(HttpUrlConnector.java:184) ~[jersey-client-2.5.1.jar:na] at org.glassfish.jersey.client.ClientRunti ...[truncated 695031 chars]... ons: query.execute(...) took 6 ms. 05:03:58.606 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 2 ms. 05:03:58.608 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [333a24d5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:03:58.610 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [49016e98]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:03:59.039 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 312.649org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT04:58:46.392 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 04:58:46.779 [Timer-3] WARN c.c.c.r.c.CloudStoreRestClient - execute: invocation failed (will NOT retry): javax.ws.rs.ProcessingException: java.net.UnknownHostException: server3.oink.org javax.ws.rs.ProcessingException: java.net.UnknownHostException: server3.oink.org at org.glassfish.jersey.client.HttpUrlConnector.apply(HttpUrlConnector.java:184) ~[jersey-client-2.5.1.jar:na] at org.glassfish.jersey.client.ClientRunti ...[truncated 695031 chars]... ons: query.execute(...) took 6 ms. 05:03:58.606 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 2 ms. 05:03:58.608 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [333a24d5]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:03:58.610 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [49016e98]close: Deferring shut down of real LocalRepoManager 20000 ms. 05:03:59.039 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-09-24T02:58:46