10533.313false011120org.subshare.core.crypto.EncrypterDecrypterStreamTest10.2390symmetricWithoutIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 914566 random bytes plaintext took 7 ms. 18:31:32.385 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 522 ms. symmetricWithoutIv: Encrypting 914566 bytes plaintext to 914582 bytes ciphertext took 167 ms. symmetricWithoutIv: Decrypting 914582 bytes ciphertext to 914566 bytes plaintext took 111 ms. symmet ...[truncated 37159 chars]... 8396 bytes plaintext took 189 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5305900 random bytes plaintext took 36 ms. asymmetricCombiWithRandomIv: Encrypting 5305900 bytes plaintext to 5306444 bytes ciphertext took 227 ms. asymmetricCombiWithRandomIv: Decrypting 5306444 bytes ciphertext to 5305900 bytes plaintext took 343 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest8.8170symmetricWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 914566 random bytes plaintext took 7 ms. 18:31:32.385 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 522 ms. symmetricWithoutIv: Encrypting 914566 bytes plaintext to 914582 bytes ciphertext took 167 ms. symmetricWithoutIv: Decrypting 914582 bytes ciphertext to 914566 bytes plaintext took 111 ms. symmet ...[truncated 37159 chars]... 8396 bytes plaintext took 189 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5305900 random bytes plaintext took 36 ms. asymmetricCombiWithRandomIv: Encrypting 5305900 bytes plaintext to 5306444 bytes ciphertext took 227 ms. asymmetricCombiWithRandomIv: Decrypting 5306444 bytes ciphertext to 5305900 bytes plaintext took 343 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest22.6480asymmetricPlainfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 914566 random bytes plaintext took 7 ms. 18:31:32.385 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 522 ms. symmetricWithoutIv: Encrypting 914566 bytes plaintext to 914582 bytes ciphertext took 167 ms. symmetricWithoutIv: Decrypting 914582 bytes ciphertext to 914566 bytes plaintext took 111 ms. symmet ...[truncated 37159 chars]... 8396 bytes plaintext took 189 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5305900 random bytes plaintext took 36 ms. asymmetricCombiWithRandomIv: Encrypting 5305900 bytes plaintext to 5306444 bytes ciphertext took 227 ms. asymmetricCombiWithRandomIv: Decrypting 5306444 bytes ciphertext to 5305900 bytes plaintext took 343 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest19.8290asymmetricCombiWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 914566 random bytes plaintext took 7 ms. 18:31:32.385 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 522 ms. symmetricWithoutIv: Encrypting 914566 bytes plaintext to 914582 bytes ciphertext took 167 ms. symmetricWithoutIv: Decrypting 914582 bytes ciphertext to 914566 bytes plaintext took 111 ms. symmet ...[truncated 37159 chars]... 8396 bytes plaintext took 189 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5305900 random bytes plaintext took 36 ms. asymmetricCombiWithRandomIv: Encrypting 5305900 bytes plaintext to 5306444 bytes ciphertext took 227 ms. asymmetricCombiWithRandomIv: Decrypting 5306444 bytes ciphertext to 5305900 bytes plaintext took 343 ms. 61.533005org.subshare.core.crypto.EncrypterDecrypterStreamTest symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 914566 random bytes plaintext took 7 ms. 18:31:32.385 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 522 ms. symmetricWithoutIv: Encrypting 914566 bytes plaintext to 914582 bytes ciphertext took 167 ms. symmetricWithoutIv: Decrypting 914582 bytes ciphertext to 914566 bytes plaintext took 111 ms. symmet ...[truncated 37159 chars]... 8396 bytes plaintext took 189 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5305900 random bytes plaintext took 36 ms. asymmetricCombiWithRandomIv: Encrypting 5305900 bytes plaintext to 5306444 bytes ciphertext took 227 ms. asymmetricCombiWithRandomIv: Decrypting 5306444 bytes ciphertext to 5305900 bytes plaintext took 343 ms. 2017-07-30T16:31:320org.subshare.core.file.EncryptedDataFileTest1.1960writeReadSimpleEncryptedDataFilefalsePASSED1.196org.subshare.core.file.EncryptedDataFileTest2017-07-30T16:31:260org.subshare.core.gpg.GnuPgTest5.5990encryptSignAndDecryptVerifyfalsePASSED>>> 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.9650encryptAndDecryptfalsePASSED>>> 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.2940readPubringGpgfalsePASSED>>> 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.0140readSecringGpgfalsePASSED>>> 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 <<< 6.872org.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-07-30T16:31:250org.subshare.core.io.LimitedInputStreamTest2.00randomLengthWithUnderlyingSameLengthfalsePASSED2.0org.subshare.core.io.LimitedInputStreamTest2017-07-30T16:31:240org.subshare.core.io.MultiInputStreamTest5.1640randomCombifalsePASSED5.164org.subshare.core.io.MultiInputStreamTest2017-07-30T16:31:250org.subshare.core.pgp.PgpTest4.3910inlineSignThenEncryptAndDecryptThenVerifyfalsePASSED18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.2740signAndVerifyViaPgpSignablefalsePASSED18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest1.5730inlineSignAndVerifyfalsePASSED18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.1870exportImportPublicKeyfalsePASSED18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest2.5910detachedSignAndVerifyfalsePASSED18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 9.016org.subshare.core.pgp.PgpTest18:31:31.019 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:31.072 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:31.115 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.117 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/pf0I-lk-6PLHXuV3DNxCLA/.gnupg 18:31:31.816 [Test ...[truncated 27550 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] 18:31:40.739 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:31:310org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest3.5720addAllDifferentUserRepoKeyPublicKeysToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0030addAllSameUserRepoKeyPublicKeyInCollectionToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0030addSameUserRepoKeyPublicKeyTwiceToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0030addDifferentUserRepoKeyPublicKeysToUserfalsePASSED3.581org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest2017-07-30T16:31:250org.subshare.core.user.UserRegistryTest3.9490addUserfalsePASSED18:31:28.011 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:28.163 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:28.240 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:28.251 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:29.479 [Test ...[truncated 13749 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 18:32:16.090 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 25607 ms. 18:32:16.152 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.442 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.575 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.user.UserRegistryTest8.3260testGpgKeyTrustLevelsfalsePASSED18:31:28.011 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:28.163 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:28.240 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:28.251 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:29.479 [Test ...[truncated 13749 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 18:32:16.090 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 25607 ms. 18:32:16.152 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.442 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.575 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.user.UserRegistryTest36.2160initUserRegistryFromGpgKeysfalsePASSED18:31:28.011 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:28.163 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:28.240 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:28.251 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:29.479 [Test ...[truncated 13749 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 18:32:16.090 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 25607 ms. 18:32:16.152 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.442 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.575 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 48.490997org.subshare.core.user.UserRegistryTest18:31:28.011 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:31:28.163 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:31:28.240 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:28.251 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.core/build/LYqIhQMVFweaRWjdcci9Qg/.gnupg 18:31:29.479 [Test ...[truncated 13749 chars]... edCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 18:32:16.090 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 25607 ms. 18:32:16.152 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.442 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 18:32:16.575 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:31:280org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest3.0790firstAddPublicKeysThenCreateUserRepoKeyRingfalsePASSED0org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest0.0460firstCreateUserRepoKeyRingThenAddPublicKeysfalsePASSED3.125org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest2017-07-30T16:31:2320org.subshare.crypto.AsymmetricKeyTest0.002257generateManySymmetricKeystrueSKIPPED0org.subshare.crypto.AsymmetricKeyTest19.5850encodeDecodeRSAwithOAEPwithSHA1andMGF1PaddingfalsePASSED0org.subshare.crypto.AsymmetricKeyTest24.00encodeDecodeRSAfalsePASSED43.586998org.subshare.crypto.AsymmetricKeyTest2017-07-30T16:32:450org.subshare.crypto.CipherTest2.7680testLookupAllSupportedCiphersfalsePASSED18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest11.10testNullAsKeyParameterfalsePASSED18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.2630testLookupCompatibilityWithJCEfalsePASSED18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest5.6350testSymmetricEncryptionWithoutIvfalsePASSED18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.20testSymmetricEncryptionCompatibilityWithJCE_BouncyCastleProviderfalsePASSED18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 19.966002org.subshare.crypto.CipherTest18:32:53.287 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 2574 msec. 18:32:53.519 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 18:32:53.536 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 18:32:53.539 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 18:32:53.540 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.111 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 18:33:09.564 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 18:33:09.714 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 18:33:09.802 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 18:33:09.962 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 2017-07-30T16:32:500org.subshare.crypto.GCMTest11.6220testEncryptionWithCipherfalsePASSED*** 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.GCMTest3.4380testEncryptionWithBCLowLevelAPIfalsePASSED*** 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.059999org.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-07-30T16:32:450org.subshare.crypto.MACBenchmark0.2540testByteOrderfalsePASSED18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 0org.subshare.crypto.MACBenchmark4.1770benchmarkCreateManyInitialisedMACCalculatorsfalsePASSED18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 0org.subshare.crypto.MACBenchmark0.1760benchmarkCreateManyUNinitialisedMACCalculatorsfalsePASSED18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 0org.subshare.crypto.MACBenchmark19.960benchmarkCalculateManyMACsWithNewMACCalculatorfalsePASSED18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 0org.subshare.crypto.MACBenchmark15.9220benchmarkCalculateManyMACsWithSameMACCalculatorfalsePASSED18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 40.489org.subshare.crypto.MACBenchmark18:32:50.602 [Test worker] INFO o.s.c.MACBenchmark - short1 = 10112 18:32:50.672 [Test worker] INFO o.s.c.MACBenchmark - short2 = 10112 18:32:50.673 [Test worker] INFO o.s.c.MACBenchmark - int1 = 10112 2017-07-30T16:32:500org.subshare.crypto.MACTest5.380testAllSupportedMACsfalsePASSED5.38org.subshare.crypto.MACTest2017-07-30T16:32:470org.subshare.crypto.ShowSupportedAlgosForDocumentation0.2540showSupportedBlockCipherModesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.0620showSupportedCipherEnginesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.0660showSupportedMACsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.0950showSupportedPaddingsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.47700003org.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-07-30T16:32:500org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest78.9090grantAndRevokeReadPermissionfalsePASSED18:33:56.793 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.151 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.819 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [57e2fb7b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3w6c-i9el6-local' 18:33:58.996 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1004 ms. 18 ...[truncated 29439 chars]... NFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 18:35:15.377 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 18:35:15.408 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 18:35:15.793 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [57e2fb7b]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:35:15.797 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 78.909org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest18:33:56.793 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.151 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.819 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [57e2fb7b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3w6c-i9el6-local' 18:33:58.996 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1004 ms. 18 ...[truncated 29439 chars]... NFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 18:35:15.377 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 18:35:15.408 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 18:35:15.793 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [57e2fb7b]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:35:15.797 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:33:560org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest68.6550grantWritePermissionWithoutGrantPermissionfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest30.5870writeWithWritePermissionInParentParentfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest26.1060writeWithWritePermissionInParentfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest24.8240writeWithWritePermissionDirectDirfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest25.620writeWithoutWritePermissionfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest28.770writeWithWritePermissionInParentParentGrantedIndirectlyfalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest30.7110writeWithWritePermissionDirectFilefalsePASSED18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 235.273org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest18:33:55.777 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:56.205 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:33:58.465 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [9bc5d9d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.local/build/repo/j5qy3vvo-ovvqw-local' 18:33:58.667 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1333 ms. 18: ...[truncated 128081 chars]... Test - Local sync...: 79.60% 18:37:51.181 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 18:37:51.205 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 18:37:51.222 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 18:37:51.252 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3d137f6f]close: Deferring shut down of real LocalRepoManager 20000 ms. 18:37:51.253 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:33:550org.subshare.local.FilePaddingLengthRandomTest18.0060testDistributionfalsePASSED18:33:56.826 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300175, _1M=148015, _10M=46847, _100M=2976, _1G=1987, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 1749557 0org.subshare.local.FilePaddingLengthRandomTest0.0450generateOneRandomLengthfalsePASSED18:33:56.826 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300175, _1M=148015, _10M=46847, _100M=2976, _1G=1987, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 1749557 18.051org.subshare.local.FilePaddingLengthRandomTest18:33:56.826 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory lengthCategory2HitCount: {_100K=300175, _1M=148015, _10M=46847, _100M=2976, _1G=1987, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 1749557 2017-07-30T16:33:510org.subshare.local.persistence.CollisionTest4.9360xorUids1falsePASSED0org.subshare.local.persistence.CollisionTest0.0020xorUids2falsePASSED4.9379997org.subshare.local.persistence.CollisionTest2017-07-30T16:33:510org.subshare.local.sign.SignableTest2.140signAndSerializeAndVerifyfalsePASSED18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1560signAndModifyCryptoKeyIdAndVerifyfalsePASSED18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1270signAndVerifyfalsePASSED18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1860signAndModifySignatureCreatedAndVerifyfalsePASSED18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1180signAndModifyCryptoRepoFileIdAndVerifyfalsePASSED18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2.7270002org.subshare.local.sign.SignableTest18:33:55.688 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:58.163 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:58.315 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1685 ms. 18:34:00.049 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:34:00.050 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 905 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.085 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.169 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.231 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.360 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.423 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:34:05.481 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:34:020org.subshare.local.sign.SignerVerifierStreamTest21.9770signRsaWithSha1falsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest0.1630signAndVerifySimpleBlockReadfalsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest21.00sha1DigestfalsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest9.1220signAndVerifyRandomBrokenfalsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest16.9120signAndVerifyRandomManySmallfalsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest3.4190signAndVerifyRandomfalsePASSED18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 72.593org.subshare.local.sign.SignerVerifierStreamTest18:33:56.448 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:33:57.585 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:33:57.715 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1285 ms. 18:33:59.040 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 18:33:59.041 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 18 ...[truncated 5059 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 18:35:09.406 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:09.437 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:35:12.824 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:34:000org.subshare.rest.client.transport.CryptreeRepoTransportTest50.3770encryptAndSignAndVerifyAndDecryptfalsePASSED18:37:57.421 [Test worker] INFO c.c.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:37:57.968 [Test worker] DEBUG o.s.c.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:37:57.987 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 18:37:57.988 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - read: Reading propertie ...[truncated 44324 chars]... y='subshare.signerTransformation' is not set (config is queried next). 18:38:47.279 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 18:38:47.412 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 18:38:47.413 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 50.377org.subshare.rest.client.transport.CryptreeRepoTransportTest18:37:57.421 [Test worker] INFO c.c.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 18:37:57.968 [Test worker] DEBUG o.s.c.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 18:37:57.987 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 18:37:57.988 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - read: Reading propertie ...[truncated 44324 chars]... y='subshare.signerTransformation' is not set (config is queried next). 18:38:47.279 [Test worker] DEBUG c.c.c.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 18:38:47.412 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 18:38:47.413 [Test worker] DEBUG o.s.r.c.t.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 2017-07-30T16:37:570org.subshare.test.BrokenSignatureIT237.950uploadBrokenSignaturefalsePASSEDJul 30, 2017 6:39:55 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:40:01 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.021 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.888 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/dsMcVzKK19Hae0OXJKeynw/.subshare/ssl.server/keystore' does not exist! 18:39:08.892 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 294171 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.BrokenSignatureIT144.0570downloadBrokenSignaturefalsePASSEDJul 30, 2017 6:39:55 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:40:01 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.021 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.888 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/dsMcVzKK19Hae0OXJKeynw/.subshare/ssl.server/keystore' does not exist! 18:39:08.892 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 294171 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] 382.00702org.subshare.test.BrokenSignatureITJul 30, 2017 6:39:55 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:40:01 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.021 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.888 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/dsMcVzKK19Hae0OXJKeynw/.subshare/ssl.server/keystore' does not exist! 18:39:08.892 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 294171 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-07-30T16:40:050org.subshare.test.CollisionOnClientRepoToRepoSyncIT208.8690newFileVsNewFileCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT184.5620newDirectoryVsNewDirectoryCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT190.3960modifiedFileVsDeletedFileCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT190.8670modifiedFileVsModifiedFileCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT151.3220deletedFileVsModifiedFileCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT112.8660newFileVsNewDirectoryCollisionOnClientfalsePASSED18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1038.8821org.subshare.test.CollisionOnClientRepoToRepoSyncIT18:54:47.427 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:54:57.988 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.228 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:55:05.576 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 920530 chars]... :12:05.679 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 17 ms. 19:12:05.880 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 12 ms. 19:12:05.941 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 4 ms. 19:12:05.942 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:12:06.316 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:54:470org.subshare.test.CollisionOnServerRepoToRepoSyncIT417.1370newFileVsNewFileUploadedCollisionOnServerfalsePASSEDJul 30, 2017 6:39:38 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:43 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.745 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.118 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/4CO__NqFqZkWcM106v27gg/.subshare/ssl.server/keystore' does not exist! 18:39:08.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 518381 chars]... RepoTransportImpl - readCryptoKeyId: took 49 ms. 18:58:16.881 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 4 ms. lastByteOfHistoFile0 = 111 lastByteOfHistoFile1 = 222 18:58:18.973 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 9 ms. 18:58:18.984 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 9 ms. 18:58:19.370 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.CollisionOnServerRepoToRepoSyncIT696.7860newFileVsNewFileUploadingCollisionOnServerfalsePASSEDJul 30, 2017 6:39:38 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:43 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.745 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.118 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/4CO__NqFqZkWcM106v27gg/.subshare/ssl.server/keystore' does not exist! 18:39:08.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 518381 chars]... RepoTransportImpl - readCryptoKeyId: took 49 ms. 18:58:16.881 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 4 ms. lastByteOfHistoFile0 = 111 lastByteOfHistoFile1 = 222 18:58:18.973 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 9 ms. 18:58:18.984 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 9 ms. 18:58:19.370 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1113.923org.subshare.test.CollisionOnServerRepoToRepoSyncITJul 30, 2017 6:39:38 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:43 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.745 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.118 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/4CO__NqFqZkWcM106v27gg/.subshare/ssl.server/keystore' does not exist! 18:39:08.128 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 518381 chars]... RepoTransportImpl - readCryptoKeyId: took 49 ms. 18:58:16.881 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 4 ms. lastByteOfHistoFile0 = 111 lastByteOfHistoFile1 = 222 18:58:18.973 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 9 ms. 18:58:18.984 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 2 elements took 9 ms. 18:58:19.370 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:450org.subshare.test.IgnoreRuleRepoToRepoSyncIT364.4630ignoreRulesAddedAfterFilesSyncedfalsePASSEDJul 30, 2017 6:39:21 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.204 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.900 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/3ghDms2vDvWPQODP64oHRw/.subshare/ssl.server/keystore' does not exist! 18:39:08.912 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 491980 chars]... d=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 localRepositoryRevisionSynced=119 18:53:36.735 [qtp1276160461-1430] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 remoteRepositoryId=8f36fff8-533a-46f5-9b61-18ee191b40cb transaction.localRevision=91 remoteFromLocalRevision=119 18:53:36.747 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:37.127 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT251.0390ignoreRulesBecomeDisabledfalsePASSEDJul 30, 2017 6:39:21 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.204 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.900 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/3ghDms2vDvWPQODP64oHRw/.subshare/ssl.server/keystore' does not exist! 18:39:08.912 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 491980 chars]... d=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 localRepositoryRevisionSynced=119 18:53:36.735 [qtp1276160461-1430] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 remoteRepositoryId=8f36fff8-533a-46f5-9b61-18ee191b40cb transaction.localRevision=91 remoteFromLocalRevision=119 18:53:36.747 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:37.127 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT233.4130ignoreRulesExistBeforeAffectedFilesfalsePASSEDJul 30, 2017 6:39:21 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.204 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.900 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/3ghDms2vDvWPQODP64oHRw/.subshare/ssl.server/keystore' does not exist! 18:39:08.912 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 491980 chars]... d=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 localRepositoryRevisionSynced=119 18:53:36.735 [qtp1276160461-1430] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 remoteRepositoryId=8f36fff8-533a-46f5-9b61-18ee191b40cb transaction.localRevision=91 remoteFromLocalRevision=119 18:53:36.747 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:37.127 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 848.91504org.subshare.test.IgnoreRuleRepoToRepoSyncITJul 30, 2017 6:39:21 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.204 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.900 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/3ghDms2vDvWPQODP64oHRw/.subshare/ssl.server/keystore' does not exist! 18:39:08.912 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 491980 chars]... d=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 localRepositoryRevisionSynced=119 18:53:36.735 [qtp1276160461-1430] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8ac4f7db-726c-4cf5-8281-2ff8dd4b33e4 remoteRepositoryId=8f36fff8-533a-46f5-9b61-18ee191b40cb transaction.localRevision=91 remoteFromLocalRevision=119 18:53:36.747 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:37.127 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:280org.subshare.test.InterruptedRepoToRepoSyncIT204.0940interruptSyncDownAndResumefalsePASSED18:46:27.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:34.832 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.225 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.830 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 242731 chars]... Id=5f7c75c0-c617-42df-8639-f567b44ae3a1 localRepositoryRevisionSynced=114 18:55:05.461 [qtp87417275-1144] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5f7c75c0-c617-42df-8639-f567b44ae3a1 remoteRepositoryId=fabaf35b-8ef0-42d6-b9cb-13b06cedbcd4 transaction.localRevision=109 remoteFromLocalRevision=114 18:55:05.470 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:55:05.943 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InterruptedRepoToRepoSyncIT314.4640interruptSyncUpAndResumefalsePASSED18:46:27.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:34.832 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.225 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.830 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 242731 chars]... Id=5f7c75c0-c617-42df-8639-f567b44ae3a1 localRepositoryRevisionSynced=114 18:55:05.461 [qtp87417275-1144] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5f7c75c0-c617-42df-8639-f567b44ae3a1 remoteRepositoryId=fabaf35b-8ef0-42d6-b9cb-13b06cedbcd4 transaction.localRevision=109 remoteFromLocalRevision=114 18:55:05.470 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:55:05.943 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 518.558org.subshare.test.InterruptedRepoToRepoSyncIT18:46:27.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:34.832 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.225 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:46:41.830 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 242731 chars]... Id=5f7c75c0-c617-42df-8639-f567b44ae3a1 localRepositoryRevisionSynced=114 18:55:05.461 [qtp87417275-1144] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5f7c75c0-c617-42df-8639-f567b44ae3a1 remoteRepositoryId=fabaf35b-8ef0-42d6-b9cb-13b06cedbcd4 transaction.localRevision=109 remoteFromLocalRevision=114 18:55:05.470 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:55:05.943 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:46:270org.subshare.test.InviteUserAndSyncIT247.1980inviteUserAndSync_singleReadPermissionOnSubdirfalsePASSED18:46:25.243 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:46:25.246 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:46:25.619 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:25.629 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:46:25.630 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 539953 chars]... remoteRepositoryId=85fad8c3-4fa1-475a-a123-21e861e5d8fa transaction.localRevision=128 remoteFromLocalRevision=127 18:59:14.731 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:59:15.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InviteUserAndSyncIT335.5210inviteUserAndSync_singleWritePermissionOnRootfalsePASSED18:46:25.243 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:46:25.246 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:46:25.619 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:25.629 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:46:25.630 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 539953 chars]... remoteRepositoryId=85fad8c3-4fa1-475a-a123-21e861e5d8fa transaction.localRevision=128 remoteFromLocalRevision=127 18:59:14.731 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:59:15.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.InviteUserAndSyncIT187.1970inviteUserAndSync_singleReadPermissionOnRootfalsePASSED18:46:25.243 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:46:25.246 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:46:25.619 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:25.629 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:46:25.630 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 539953 chars]... remoteRepositoryId=85fad8c3-4fa1-475a-a123-21e861e5d8fa transaction.localRevision=128 remoteFromLocalRevision=127 18:59:14.731 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:59:15.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 769.916org.subshare.test.InviteUserAndSyncIT18:46:25.243 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:46:25.246 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:46:25.619 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:46:25.629 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:46:25.630 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 539953 chars]... remoteRepositoryId=85fad8c3-4fa1-475a-a123-21e861e5d8fa transaction.localRevision=128 remoteFromLocalRevision=127 18:59:14.731 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<< inviteUserAndSync_singleReadPermissionOnRoot <<< *** 18:59:14.805 [Test worker] INFO o.s.t.InviteUserAndSyncIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:59:15.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:46:250org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT385.9550inviteUserAndSync_withMetaOnly_singleWritePermissionOnRootfalsePASSED18:47:00.599 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:47:00.599 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:47:00.883 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:47:00.890 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:47:00.893 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 241195 chars]... 76d206 transaction.localRevision=168 remoteFromLocalRevision=164 18:53:26.032 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:26.111 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<< inviteUserAndSync_withMetaOnly_singleWritePermissionOnRoot <<< *** 18:53:26.112 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:53:26.540 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 385.955org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT18:47:00.599 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 18:47:00.599 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 18:47:00.883 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:47:00.890 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:47:00.893 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 241195 chars]... 76d206 transaction.localRevision=168 remoteFromLocalRevision=164 18:53:26.032 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:53:26.111 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<< inviteUserAndSync_withMetaOnly_singleWritePermissionOnRoot <<< *** 18:53:26.112 [Test worker] INFO o.s.t.InviteUserWithNormalAndMetaOnlyRepoIT - *** <<<<<<<<<<<<<<<<<<<<<<<<< *** 18:53:26.540 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:47:000org.subshare.test.Issue4IT177.8490issue_4_syncFileDeletedAfterLocalSyncfalsePASSED18:51:48.887 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:52:01.473 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:52:07.529 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:52:07.729 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 65070 chars]... yId=e596e286-c130-4ecb-a399-1aa43d60eb2e localRepositoryRevisionSynced=143 18:54:46.223 [qtp788369844-906] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e596e286-c130-4ecb-a399-1aa43d60eb2e remoteRepositoryId=bb4e17b4-9dd0-4c4d-9a64-eed8acc13d03 transaction.localRevision=79 remoteFromLocalRevision=143 18:54:46.310 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:54:46.728 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 177.849org.subshare.test.Issue4IT18:51:48.887 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:52:01.473 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:52:07.529 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:52:07.729 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 65070 chars]... yId=e596e286-c130-4ecb-a399-1aa43d60eb2e localRepositoryRevisionSynced=143 18:54:46.223 [qtp788369844-906] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e596e286-c130-4ecb-a399-1aa43d60eb2e remoteRepositoryId=bb4e17b4-9dd0-4c4d-9a64-eed8acc13d03 transaction.localRevision=79 remoteFromLocalRevision=143 18:54:46.310 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:54:46.728 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:51:480org.subshare.test.Issue5IT448.0480issue_5_inviteMultipleFriendsfalsePASSEDJul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:29 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.848 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:09.156 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/7_26bWclBbXkGb5Sv0yQDg/.subshare/ssl.server/keystore' does not exist! 18:39:09.166 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 303505 chars]... egistry: org.subshare.test.AbstractUserRegistryIT$2@4a67d3f3 (of khaled) 18:47:00.089 [qtp1011194845-631] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=261c0d70-c248-4552-9a98-e8bb0d575d2e remoteRepositoryId=137b26b5-6627-4e1a-aef8-110606ace23f transaction.localRevision=177 remoteFromLocalRevision=144 18:47:00.193 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:47:00.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 448.048org.subshare.test.Issue5ITJul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:29 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:05.848 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:09.156 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/7_26bWclBbXkGb5Sv0yQDg/.subshare/ssl.server/keystore' does not exist! 18:39:09.166 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 303505 chars]... egistry: org.subshare.test.AbstractUserRegistryIT$2@4a67d3f3 (of khaled) 18:47:00.089 [qtp1011194845-631] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=261c0d70-c248-4552-9a98-e8bb0d575d2e remoteRepositoryId=137b26b5-6627-4e1a-aef8-110606ace23f transaction.localRevision=177 remoteFromLocalRevision=144 18:47:00.193 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:47:00.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:320org.subshare.test.Issue8IT514.7350issue_8_threesomeWithFreshmenfalsePASSED 18:58:26.906 [Test worker] INFO o.s.t.AbstractMultiUserIT - 18:58:26.907 [Test worker] INFO o.s.t.AbstractMultiUserIT - ******************************************************************** 18:58:26.907 [Test worker] INFO o.s.t.AbstractMultiUserIT - *** marco *** 18:58:26.913 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:58:26.914 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<GnuPgDir>.getInstance: testUser=marco 18:58:26.916 [Test worker] INFO o.s.t.PgpTestU ...[truncated 909643 chars]... 41f850 transaction.localRevision=226 remoteFromLocalRevision=148 19:06:51.570 [Test worker] INFO o.s.t.AbstractMultiUserIT - Synchronising...: 100.00% 19:06:51.572 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<ServerRepoRegistryImpl>.getInstance: testUser=xenia 19:06:51.572 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 19:06:51.614 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 514.735org.subshare.test.Issue8IT 18:58:26.906 [Test worker] INFO o.s.t.AbstractMultiUserIT - 18:58:26.907 [Test worker] INFO o.s.t.AbstractMultiUserIT - ******************************************************************** 18:58:26.907 [Test worker] INFO o.s.t.AbstractMultiUserIT - *** marco *** 18:58:26.913 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:58:26.914 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<GnuPgDir>.getInstance: testUser=marco 18:58:26.916 [Test worker] INFO o.s.t.PgpTestU ...[truncated 909643 chars]... 41f850 transaction.localRevision=226 remoteFromLocalRevision=148 19:06:51.570 [Test worker] INFO o.s.t.AbstractMultiUserIT - Synchronising...: 100.00% 19:06:51.572 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<ServerRepoRegistryImpl>.getInstance: testUser=xenia 19:06:51.572 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 19:06:51.614 [Test worker] INFO o.s.t.AbstractMultiUserIT - MockUp<UserRegistryImpl>.getInstance: testUser=xenia 2017-07-30T16:58:200org.subshare.test.LocalServerClientIT2.390invokeSimpleStaticMethodfalsePASSEDJul 30, 2017 6:39:20 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:03.830 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:07.022 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_hEMdsdckOFPakcwcoGs9A/.subshare/ssl.server/keystore' does not exist! 18:39:07.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75977 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] 18:39:37.280 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.LocalServerClientIT6.040invokeDeniedMethodsfalsePASSEDJul 30, 2017 6:39:20 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:03.830 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:07.022 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_hEMdsdckOFPakcwcoGs9A/.subshare/ssl.server/keystore' does not exist! 18:39:07.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75977 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] 18:39:37.280 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 8.43org.subshare.test.LocalServerClientITJul 30, 2017 6:39:20 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:25 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:03.830 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:07.022 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/_hEMdsdckOFPakcwcoGs9A/.subshare/ssl.server/keystore' does not exist! 18:39:07.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 75977 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] 18:39:37.280 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:280org.subshare.test.LockerSyncIT22.7150syncServerRegistryfalsePASSED18:55:10.057 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:55:10.066 [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/dsMcVzKK19Hae0OXJKeynw/client1/.gnupg/pubring.gpg' 18:55:10.067 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 171526 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='RcIG3ip2kV0FzGDxg8QFAg' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 18:55:41.164 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 18:55:41.167 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 18:55:41.278 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 0org.subshare.test.LockerSyncIT14.4360syncUserRegistryfalsePASSED18:55:10.057 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:55:10.066 [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/dsMcVzKK19Hae0OXJKeynw/client1/.gnupg/pubring.gpg' 18:55:10.067 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 171526 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='RcIG3ip2kV0FzGDxg8QFAg' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 18:55:41.164 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 18:55:41.167 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 18:55:41.278 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 37.151org.subshare.test.LockerSyncIT18:55:10.057 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 18:55:10.066 [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/dsMcVzKK19Hae0OXJKeynw/client1/.gnupg/pubring.gpg' 18:55:10.067 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/secring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subs ...[truncated 171526 chars]... ker] INFO o.s.c.l.s.LockerSync - syncDown: serverId='RcIG3ip2kV0FzGDxg8QFAg' serverName='null' pgpKeyId=d7a92a24aa97ddbd lockerContentName='userRegistry.subshare' 18:55:41.164 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 18:55:41.167 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - decodeEncrypted: encryptingPgpKeyIds=[a58da7d810b74edf] 18:55:41.278 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 2017-07-30T16:55:060org.subshare.test.MetaOnlyRepoSyncIT294.2990syncFromLocalToRemoteToMetaOnlyAddingFilesfalsePASSEDJul 30, 2017 6:39:41 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:46 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.581 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/g1xQhOuDL66BLHbTGUBzbw/.subshare/ssl.server/keystore' does not exist! 18:39:08.047 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 282574 chars]... c398af4e-58a0-470d-9cc6-84aaa15196dc remoteRepositoryId=9f01fa72-f9c2-40e5-bd6b-b969fea5b0e8 transaction.localRevision=12 remoteFromLocalRevision=111 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 60.16% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:51:48.546 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.MetaOnlyRepoSyncIT204.7210syncFromLocalToRemoteToMetaOnlyfalsePASSEDJul 30, 2017 6:39:41 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:46 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.581 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/g1xQhOuDL66BLHbTGUBzbw/.subshare/ssl.server/keystore' does not exist! 18:39:08.047 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 282574 chars]... c398af4e-58a0-470d-9cc6-84aaa15196dc remoteRepositoryId=9f01fa72-f9c2-40e5-bd6b-b969fea5b0e8 transaction.localRevision=12 remoteFromLocalRevision=111 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 60.16% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:51:48.546 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.MetaOnlyRepoSyncIT220.0690syncFromLocalToRemoteToMetaOnlyRemovingFilesfalsePASSEDJul 30, 2017 6:39:41 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:46 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.581 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/g1xQhOuDL66BLHbTGUBzbw/.subshare/ssl.server/keystore' does not exist! 18:39:08.047 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 282574 chars]... c398af4e-58a0-470d-9cc6-84aaa15196dc remoteRepositoryId=9f01fa72-f9c2-40e5-bd6b-b969fea5b0e8 transaction.localRevision=12 remoteFromLocalRevision=111 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 60.16% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:51:48.546 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 719.089org.subshare.test.MetaOnlyRepoSyncITJul 30, 2017 6:39:41 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... Jul 30, 2017 6:39:46 PM org.glassfish.jersey.server.ApplicationHandler initialize INFO: Initiating Jersey application, version Jersey: 2.5.1 2014-01-02 13:43:00... 18:39:04.581 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 18:39:08.037 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: keyStoreFile='/home/tomcat/.jenkins/workspace/org.subshare/org.subshare/org.subshare.test/build/jvm/g1xQhOuDL66BLHbTGUBzbw/.subshare/ssl.server/keystore' does not exist! 18:39:08.047 [cloudStoreServerThread] INFO c.c.c.s.CloudStoreServer - initKeyStore: Creating RSA key pair (this might take a whil ...[truncated 282574 chars]... c398af4e-58a0-470d-9cc6-84aaa15196dc remoteRepositoryId=9f01fa72-f9c2-40e5-bd6b-b969fea5b0e8 transaction.localRevision=12 remoteFromLocalRevision=111 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 60.16% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 80.08% 18:51:48.154 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:51:48.546 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:490org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT398.6770parentConfigWithRemotePathPrefixfalsePASSED18:39:46.009 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:39:52.924 [qtp1749262391-27] ERROR c.c.c.l.r.s.DefaultExceptionMapper - java.lang.IllegalArgumentException: ObjectManager[zhSRt5igEbL6dhIQZ4_p5A] does not have object for this ObjectRef: ObjectRef[zhSRt5igEbL6dhIQZ4_p5A, 0, 0] java.lang.IllegalArgumentException: ObjectManager[zhSRt5igEbL6dhIQZ4_p5A] does not have object for this ObjectRef: ObjectRef[zhSRt5igEbL6dhIQZ4_p5A, 0, 0] at co.codewizards.cloudstore.ls.core.i ...[truncated 261817 chars]... Id=991538ee-d13f-4309-9f11-db8d79e9685c localRepositoryRevisionSynced=120 18:46:23.987 [qtp192490287-523] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=991538ee-d13f-4309-9f11-db8d79e9685c remoteRepositoryId=72175286-75c7-449c-a283-d7bc2e6b2d14 transaction.localRevision=224 remoteFromLocalRevision=120 18:46:24.066 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:46:24.641 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 398.677org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT18:39:46.009 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:39:52.924 [qtp1749262391-27] ERROR c.c.c.l.r.s.DefaultExceptionMapper - java.lang.IllegalArgumentException: ObjectManager[zhSRt5igEbL6dhIQZ4_p5A] does not have object for this ObjectRef: ObjectRef[zhSRt5igEbL6dhIQZ4_p5A, 0, 0] java.lang.IllegalArgumentException: ObjectManager[zhSRt5igEbL6dhIQZ4_p5A] does not have object for this ObjectRef: ObjectRef[zhSRt5igEbL6dhIQZ4_p5A, 0, 0] at co.codewizards.cloudstore.ls.core.i ...[truncated 261817 chars]... Id=991538ee-d13f-4309-9f11-db8d79e9685c localRepositoryRevisionSynced=120 18:46:23.987 [qtp192490287-523] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=991538ee-d13f-4309-9f11-db8d79e9685c remoteRepositoryId=72175286-75c7-449c-a283-d7bc2e6b2d14 transaction.localRevision=224 remoteFromLocalRevision=120 18:46:24.066 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 18:46:24.641 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:39:460org.subshare.test.PermissionIT296.9880interruptPermissionSetInheritancefalsePASSED18:53:37.532 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:49.610 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:13.279 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:14.755 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 768219 chars]... Id=f6470460-29da-4b26-adf3-4494d28d122e localRepositoryRevisionSynced=94 19:08:25.396 [qtp1276160461-3869] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f6470460-29da-4b26-adf3-4494d28d122e remoteRepositoryId=e3843c3d-9d30-4829-b703-1b55b4911c35 transaction.localRevision=183 remoteFromLocalRevision=94 19:08:25.455 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:08:25.901 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.PermissionIT282.9220uploadBackdatedSignaturefalsePASSED18:53:37.532 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:49.610 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:13.279 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:14.755 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 768219 chars]... Id=f6470460-29da-4b26-adf3-4494d28d122e localRepositoryRevisionSynced=94 19:08:25.396 [qtp1276160461-3869] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f6470460-29da-4b26-adf3-4494d28d122e remoteRepositoryId=e3843c3d-9d30-4829-b703-1b55b4911c35 transaction.localRevision=183 remoteFromLocalRevision=94 19:08:25.455 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:08:25.901 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.PermissionIT308.4560nonOwnerAdminGrantsWritePermissionfalsePASSED18:53:37.532 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:49.610 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:13.279 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:14.755 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 768219 chars]... Id=f6470460-29da-4b26-adf3-4494d28d122e localRepositoryRevisionSynced=94 19:08:25.396 [qtp1276160461-3869] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f6470460-29da-4b26-adf3-4494d28d122e remoteRepositoryId=e3843c3d-9d30-4829-b703-1b55b4911c35 transaction.localRevision=183 remoteFromLocalRevision=94 19:08:25.455 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:08:25.901 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 888.366org.subshare.test.PermissionIT18:53:37.532 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:49.610 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:13.279 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:54:14.755 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 768219 chars]... Id=f6470460-29da-4b26-adf3-4494d28d122e localRepositoryRevisionSynced=94 19:08:25.396 [qtp1276160461-3869] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f6470460-29da-4b26-adf3-4494d28d122e remoteRepositoryId=e3843c3d-9d30-4829-b703-1b55b4911c35 transaction.localRevision=183 remoteFromLocalRevision=94 19:08:25.455 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:08:25.901 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:53:370org.subshare.test.PgpSyncIT3.7870testPgpSyncfalsePASSED19:08:28.815 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:08:32.595 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 3.787org.subshare.test.PgpSyncIT19:08:28.815 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:08:32.595 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T17:08:280org.subshare.test.ReadUserIdentityIT209.0490inviteWithReadInvitationAndGrantReadUserIdentityPermissionLaterfalsePASSED19:06:54.948 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 19:06:54.949 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 19:06:55.390 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:06:55.391 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 19:06:55.392 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 244245 chars]... egistry: org.subshare.test.AbstractUserRegistryIT$2@747f7c9f (of marco) 19:10:23.200 [qtp1296822510-3742] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=50860168-bd33-41c3-a891-18e6eb872368 remoteRepositoryId=694d7303-6549-4d1a-99c6-a055584090bc transaction.localRevision=170 remoteFromLocalRevision=198 19:10:23.204 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:10:23.992 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 209.049org.subshare.test.ReadUserIdentityIT19:06:54.948 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>>>>>>>>>>>>> *** 19:06:54.949 [Test worker] INFO o.s.t.AbstractUserRegistryIT - *** >>> before >>> *** 19:06:55.390 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:06:55.391 [Test worker] INFO o.s.t.PgpTestUtil - setupPgp: ownerName=marco 19:06:55.392 [Test worker] INFO o.s.t.PgpTestUtil - copyResource: sourceResName='gpg/marco/pubring.gpg' destinationFile='/home/tomcat/.jenkins/workspace/org.subshare/org. ...[truncated 244245 chars]... egistry: org.subshare.test.AbstractUserRegistryIT$2@747f7c9f (of marco) 19:10:23.200 [qtp1296822510-3742] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=50860168-bd33-41c3-a891-18e6eb872368 remoteRepositoryId=694d7303-6549-4d1a-99c6-a055584090bc transaction.localRevision=170 remoteFromLocalRevision=198 19:10:23.204 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:10:23.992 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T17:06:540org.subshare.test.RepoToRepoSyncIT323.5480syncFromLocalToRemoteToLocalWithPathPrefixWithWritePermissionGrantedAndRevokedfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT145.4970syncFromLocalToRemoteToLocalfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT173.570syncFromLocalToRemoteToLocalWithPathPrefixWithoutSubdirClearanceKeyfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT154.9750syncFromLocalToRemoteToLocalWithPathPrefixfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT172.5920syncFromLocalToRemoteToLocalThenDeleteFileAndSyncAgainfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT181.970syncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 20org.subshare.test.RepoToRepoSyncIT0.0257syncFromLocalToRemoteToLocalThenCauseDeleteCollisionOnServerDuringUpSynctrueSKIPPED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.test.RepoToRepoSyncIT100.8020multiSyncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 1252.954org.subshare.test.RepoToRepoSyncIT18:53:26.680 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 18:53:40.490 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:58.415 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 18:53:59.732 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 1093717 chars]... =146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 localRepositoryRevisionSynced=101 19:14:19.200 [qtp1011194845-5101] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=146fa8e7-6ae1-492d-9c1b-a81bc654e8a4 remoteRepositoryId=731bbf2d-f251-49c4-adab-64e500b48249 transaction.localRevision=166 remoteFromLocalRevision=101 19:14:19.203 [Test worker] INFO o.s.t.AbstractRepoToRepoSyncIT - Synchronising...: 100.00% 19:14:19.639 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T16:53:260org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT88.2170newFileVsNewSymlinkCollisionOnClientfalsePASSED19:14:20.040 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:14:23.252 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 19:14:25.300 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 19:14:25.445 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 150477 chars]... 54f-ab06-1b07cdd212aa renewalDateTime=2017-07-30T17:44:45.152Z expiryDateTime=2017-07-30T18:14:45.152Z 19:15:47.832 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 4 ms. 19:15:47.890 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 9 ms. 19:15:47.891 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:15:48.252 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 88.217org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT19:14:20.040 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 19:14:23.252 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 19:14:25.300 [Test worker] INFO D.Datastore - The class "org.subshare.local.persistence.SignatureImpl" is tagged as "embedded-only" so does not have its own datastore table. 19:14:25.445 [Test worker] INFO D.Datastore - The class "org.subshare.loc ...[truncated 150477 chars]... 54f-ab06-1b07cdd212aa renewalDateTime=2017-07-30T17:44:45.152Z expiryDateTime=2017-07-30T18:14:45.152Z 19:15:47.832 [Test worker] INFO o.s.r.c.t.CryptreeRestRepoTransportImpl - readCryptoKeyId: took 4 ms. 19:15:47.890 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: query.execute(...) took 9 ms. 19:15:47.891 [Test worker] INFO o.s.l.p.CollisionDao - getCollisions: Loading result-set with 1 elements took 1 ms. 19:15:48.252 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2017-07-30T17:14:20