11267.88false112340org.subshare.core.crypto.EncrypterDecrypterStreamTest27.5120symmetricWithoutIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10327731 random bytes plaintext took 491 ms. 13:06:42.234 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 1345 ms. symmetricWithoutIv: Encrypting 10327731 bytes plaintext to 10327750 bytes ciphertext took 920 ms. symmetricWithoutIv: Decrypting 10327750 bytes ciphertext to 10327731 bytes plaintext took 10 ...[truncated 37167 chars]... 99896 bytes plaintext took 54 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5336814 random bytes plaintext took 45 ms. asymmetricCombiWithRandomIv: Encrypting 5336814 bytes plaintext to 5337358 bytes ciphertext took 285 ms. asymmetricCombiWithRandomIv: Decrypting 5337358 bytes ciphertext to 5336814 bytes plaintext took 314 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest16.2620symmetricWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10327731 random bytes plaintext took 491 ms. 13:06:42.234 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 1345 ms. symmetricWithoutIv: Encrypting 10327731 bytes plaintext to 10327750 bytes ciphertext took 920 ms. symmetricWithoutIv: Decrypting 10327750 bytes ciphertext to 10327731 bytes plaintext took 10 ...[truncated 37167 chars]... 99896 bytes plaintext took 54 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5336814 random bytes plaintext took 45 ms. asymmetricCombiWithRandomIv: Encrypting 5336814 bytes plaintext to 5337358 bytes ciphertext took 285 ms. asymmetricCombiWithRandomIv: Decrypting 5337358 bytes ciphertext to 5336814 bytes plaintext took 314 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest9.0650asymmetricPlainfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10327731 random bytes plaintext took 491 ms. 13:06:42.234 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 1345 ms. symmetricWithoutIv: Encrypting 10327731 bytes plaintext to 10327750 bytes ciphertext took 920 ms. symmetricWithoutIv: Decrypting 10327750 bytes ciphertext to 10327731 bytes plaintext took 10 ...[truncated 37167 chars]... 99896 bytes plaintext took 54 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5336814 random bytes plaintext took 45 ms. asymmetricCombiWithRandomIv: Encrypting 5336814 bytes plaintext to 5337358 bytes ciphertext took 285 ms. asymmetricCombiWithRandomIv: Decrypting 5337358 bytes ciphertext to 5336814 bytes plaintext took 314 ms. 0org.subshare.core.crypto.EncrypterDecrypterStreamTest14.3140asymmetricCombiWithRandomIvfalsePASSED symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10327731 random bytes plaintext took 491 ms. 13:06:42.234 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 1345 ms. symmetricWithoutIv: Encrypting 10327731 bytes plaintext to 10327750 bytes ciphertext took 920 ms. symmetricWithoutIv: Decrypting 10327750 bytes ciphertext to 10327731 bytes plaintext took 10 ...[truncated 37167 chars]... 99896 bytes plaintext took 54 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5336814 random bytes plaintext took 45 ms. asymmetricCombiWithRandomIv: Encrypting 5336814 bytes plaintext to 5337358 bytes ciphertext took 285 ms. asymmetricCombiWithRandomIv: Decrypting 5337358 bytes ciphertext to 5336814 bytes plaintext took 314 ms. 67.153org.subshare.core.crypto.EncrypterDecrypterStreamTest symmetricWithoutIv: cipherTransformation=AES_CBC_PKCS5PADDING symmetricWithoutIv: Generating 10327731 random bytes plaintext took 491 ms. 13:06:42.234 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory symmetricWithoutIv: Creating symmetric key took 1345 ms. symmetricWithoutIv: Encrypting 10327731 bytes plaintext to 10327750 bytes ciphertext took 920 ms. symmetricWithoutIv: Decrypting 10327750 bytes ciphertext to 10327731 bytes plaintext took 10 ...[truncated 37167 chars]... 99896 bytes plaintext took 54 ms. asymmetricCombiWithRandomIv: asymmetricCipherTransformation=RSA_OAEPWITHSHA1ANDMGF1PADDING asymmetricCombiWithRandomIv: symmetricCipherTransformation=TWOFISH_CFB_NOPADDING asymmetricCombiWithRandomIv: Generating 5336814 random bytes plaintext took 45 ms. asymmetricCombiWithRandomIv: Encrypting 5336814 bytes plaintext to 5337358 bytes ciphertext took 285 ms. asymmetricCombiWithRandomIv: Decrypting 5337358 bytes ciphertext to 5336814 bytes plaintext took 314 ms. 2022-02-02T12:06:410org.subshare.core.file.EncryptedDataFileTest1.2460writeReadSimpleEncryptedDataFilefalsePASSED1.246org.subshare.core.file.EncryptedDataFileTest2022-02-02T12:06:440org.subshare.core.gpg.GnuPgTest5.7790encryptSignAndDecryptVerifyfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 0org.subshare.core.gpg.GnuPgTest1.7780encryptAndDecryptfalsePASSED>>> 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.5440readPubringGpgfalsePASSED>>> 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.0360readSecringGpgfalsePASSED>>> pub >>> keyID: 16686379b818da48 fingerprint: E8:3E:05:6D:B9:48:9E:0E:4B:56:05:89:16:68:63:79:B8:18:DA:48 masterKey: true encryptionKey: false userID: Niklas Schiffler <nick@nightlabs.de> userID: Niklas Schiffler <nschiffler@nightlabs.de> signature.keyID: 16686379b818da48 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: 3a7e3c9cbce48dc2 signature.signatureType: POSITIVE_CERTIFICATION signature.keyID: ead508863c3db998 signature.signatureType: POSITIVE_CERTIFICATION signature.ke ...[truncated 26887 chars]... ewizards.co> signature.keyID: d7a92a24aa97ddbd signature.signatureType: POSITIVE_CERTIFICATION <<< pub <<< >>> pub >>> keyID: a58da7d810b74edf fingerprint: 6F:EA:38:9D:83:64:EE:9E:0C:D9:9B:86:A5:8D:A7:D8:10:B7:4E:DF masterKey: false encryptionKey: true signature.keyID: d7a92a24aa97ddbd signature.signatureType: SUBKEY_BINDING <<< pub <<< >>> sec >>> keyID: d7a92a24aa97ddbd userID: Marco หงุ่ยตระกูล-Schulze (TEST) <marco@codewizards.co> <<< sec <<< >>> sec >>> keyID: a58da7d810b74edf <<< sec <<< 8.137org.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 <<< 2022-02-02T12:06:390org.subshare.core.io.LimitedInputStreamTest2.3920randomLengthWithUnderlyingSameLengthfalsePASSED2.392org.subshare.core.io.LimitedInputStreamTest2022-02-02T12:06:380org.subshare.core.io.MultiInputStreamTest5.0330randomCombifalsePASSED5.033org.subshare.core.io.MultiInputStreamTest2022-02-02T12:06:390org.subshare.core.pgp.PgpTest10.1470inlineSignThenEncryptAndDecryptThenVerifyfalsePASSED13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.1580signAndVerifyViaPgpSignablefalsePASSED13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest8.1770inlineSignAndVerifyfalsePASSED13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest0.2470exportImportPublicKeyfalsePASSED13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.pgp.PgpTest5.7530detachedSignAndVerifyfalsePASSED13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 24.482org.subshare.core.pgp.PgpTest13:06:40.684 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:40.738 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:40.842 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:40.858 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/edzUpxoH4V19I3zZQDhaLg/.gnupg 13:06:42.19 ...[truncated 28385 chars]... le.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:07:06.349 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:06:410org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest1.5980addAllDifferentUserRepoKeyPublicKeysToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0010addAllSameUserRepoKeyPublicKeyInCollectionToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0040addSameUserRepoKeyPublicKeyTwiceToUserfalsePASSED0org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest0.0110addDifferentUserRepoKeyPublicKeysToUserfalsePASSED1.6140001org.subshare.core.user.AddDuplicateUserRepoKeyPublicKeyToUserTest2022-02-02T12:06:380org.subshare.core.user.UserRegistryTest2.480addUserfalsePASSED13:06:47.458 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:47.502 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:47.558 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.560 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.772 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:47.774 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.775 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.776 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:47.785 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:47.786 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:47.789 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:47.843 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.918 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:49.949 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.955 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.956 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.966 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:49.967 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:49.997 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.998 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:50.499 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.002 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.505 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.006 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.507 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.009 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.512 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.013 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.515 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.017 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.520 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.023 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.526 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.028 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.530 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.032 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.123 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:06:58.597 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.601 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.713 [Test worker] INFO o.b.o.w.i.TrustDbImpl - updateTrustDb: Next trust-db expiration date: 2023-07-28T13:34:33 13:06:58.767 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.775 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.800 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.801 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:58.805 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:58.807 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:58.833 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:58.834 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.335 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.837 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.338 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.840 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.341 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.843 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.346 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.848 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.350 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.853 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.355 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.857 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.358 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.860 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.361 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.864 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.368 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.869 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.372 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.730 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:07:08.928 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.290 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 2357 ms. 13:07:11.304 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.425 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.479 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 4org.subshare.core.user.UserRegistryTest8.821org.junit.ComparisonFailure: expected:<[fully trust]ed> but was:<[expir]ed>org.junit.ComparisonFailure: expected:<[fully trust]ed> but was:<[expir]ed> at org.subshare.core/org.subshare.core.user.UserRegistryTest.testGpgKeyTrustLevels(UserRegistryTest.java:110) at java.base/java.lang.Thread.run(Thread.java:834) 51testGpgKeyTrustLevelsfalseFAILED13:06:47.458 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:47.502 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:47.558 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.560 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.772 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:47.774 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.775 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.776 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:47.785 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:47.786 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:47.789 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:47.843 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.918 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:49.949 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.955 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.956 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.966 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:49.967 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:49.997 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.998 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:50.499 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.002 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.505 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.006 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.507 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.009 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.512 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.013 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.515 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.017 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.520 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.023 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.526 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.028 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.530 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.032 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.123 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:06:58.597 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.601 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.713 [Test worker] INFO o.b.o.w.i.TrustDbImpl - updateTrustDb: Next trust-db expiration date: 2023-07-28T13:34:33 13:06:58.767 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.775 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.800 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.801 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:58.805 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:58.807 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:58.833 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:58.834 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.335 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.837 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.338 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.840 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.341 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.843 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.346 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.848 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.350 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.853 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.355 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.857 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.358 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.860 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.361 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.864 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.368 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.869 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.372 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.730 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:07:08.928 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.290 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 2357 ms. 13:07:11.304 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.425 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.479 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.core.user.UserRegistryTest12.7060initUserRegistryFromGpgKeysfalsePASSED13:06:47.458 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:47.502 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:47.558 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.560 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.772 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:47.774 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.775 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.776 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:47.785 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:47.786 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:47.789 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:47.843 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.918 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:49.949 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.955 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.956 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.966 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:49.967 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:49.997 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.998 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:50.499 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.002 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.505 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.006 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.507 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.009 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.512 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.013 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.515 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.017 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.520 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.023 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.526 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.028 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.530 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.032 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.123 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:06:58.597 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.601 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.713 [Test worker] INFO o.b.o.w.i.TrustDbImpl - updateTrustDb: Next trust-db expiration date: 2023-07-28T13:34:33 13:06:58.767 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.775 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.800 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.801 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:58.805 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:58.807 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:58.833 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:58.834 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.335 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.837 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.338 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.840 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.341 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.843 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.346 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.848 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.350 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.853 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.355 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.857 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.358 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.860 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.361 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.864 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.368 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.869 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.372 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.730 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:07:08.928 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.290 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 2357 ms. 13:07:11.304 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.425 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.479 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 24.007org.subshare.core.user.UserRegistryTest13:06:47.458 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:06:47.502 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:47.558 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.560 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.772 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:47.774 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.775 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:47.776 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:47.784 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:47.785 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:47.786 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:47.789 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:47.843 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.918 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:49.949 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:49.955 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.956 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.966 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:49.967 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:49.968 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:49.970 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:49.971 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:49.997 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:49.998 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:50.499 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.002 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:51.505 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.006 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:52.507 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.009 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:53.512 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.013 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:54.515 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.017 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:55.520 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.023 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:56.526 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.028 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:57.530 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.032 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:58.123 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:06:58.597 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.601 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:06:58.713 [Test worker] INFO o.b.o.w.i.TrustDbImpl - updateTrustDb: Next trust-db expiration date: 2023-07-28T13:34:33 13:06:58.767 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.775 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.776 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:06:58.798 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.800 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg 13:06:58.801 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/secring.gpg' 13:06:58.805 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=70c642ca41cd4390 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=49bd4210ee77b7f8 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=d7a92a24aa97ddbd 13:06:58.806 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: read secretKey with pgpKeyId=a58da7d810b74edf 13:06:58.807 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/pubring.gpg' 13:06:58.833 [Test worker] DEBUG o.s.c.p.g.TrustDbFactory - createTrustDb: Creating *real* TrustDb instance. 13:06:58.834 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.335 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:06:59.837 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.338 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:00.840 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.341 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:01.843 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.346 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:02.848 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.350 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:03.853 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.355 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:04.857 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.358 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:05.860 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.361 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:06.864 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.368 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:07.869 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.372 [Test worker] WARN o.b.o.w.IoRandomAccessFile - Locking file '/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.core/build/8ezxFNVKNXBBuSl8YYZcOg/.gnupg/trustdb.gpg' failed. Retrying. 13:07:08.730 [TrustDbFactory.deferredCloseTimer] DEBUG o.s.c.p.g.TrustDbFactory$DeferredCloseTimerTask - run: Closing *real* TrustDb instance. 13:07:08.928 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.290 [Test worker] INFO o.s.c.c.KeyFactory - Creating asymmetric key pair with defaults took 2357 ms. 13:07:11.304 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.425 [Test worker] DEBUG o.s.c.p.PgpRegistry - getPgpOrFail: returning existing Pgp instance. 13:07:11.479 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:06:470org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest0.9980firstAddPublicKeysThenCreateUserRepoKeyRingfalsePASSED0org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest0.1130firstCreateUserRepoKeyRingThenAddPublicKeysfalsePASSED1.1110001org.subshare.core.user.UserRepoKeyRingVsPublicKeysUserImplTest2022-02-02T12:06:3844org.subshare.crypto.AsymmetricKeyTest0.00111generateManySymmetricKeystrueSKIPPED0org.subshare.crypto.AsymmetricKeyTest14.4780encodeDecodeRSAwithOAEPwithSHA1andMGF1PaddingfalsePASSED0org.subshare.crypto.AsymmetricKeyTest4.4980encodeDecodeRSAfalsePASSED18.977001org.subshare.crypto.AsymmetricKeyTest2022-02-02T12:08:020org.subshare.crypto.CipherTest5.7180testLookupAllSupportedCiphersfalsePASSED13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest12.3590testNullAsKeyParameterfalsePASSED13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.180testLookupCompatibilityWithJCEfalsePASSED13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest8.0820testSymmetricEncryptionWithoutIvfalsePASSED13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 0org.subshare.crypto.CipherTest0.1410testSymmetricEncryptionCompatibilityWithJCE_BouncyCastleProviderfalsePASSED13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 26.480001org.subshare.crypto.CipherTest13:08:09.214 [Test worker] INFO o.s.c.CipherTest - testLookupAllSupportedCiphers: Successfully created 29934 ciphers in 4043 msec. 13:08:09.688 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST// 13:08:09.697 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyParameter: Transformation "AES.FAST//" does not support IV => Skipping. 13:08:09.708 [Test worker] INFO o.s.c.CipherTest - transformation=AES.FAST//NOPADDING 13:08:09.709 [Test worker] INFO o.s.c.CipherTest - testNullAsKeyP ...[truncated 3456005 chars]... s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:27.677 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 13:08:28.371 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CBC/PKCS5Padding 13:08:29.002 [Test worker] INFO o.s.c.CipherTest - transformation=AES/CFB/NoPadding 13:08:29.057 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CBC/PKCS5Padding 13:08:29.332 [Test worker] INFO o.s.c.CipherTest - transformation=Twofish/CFB/NoPadding 2022-02-02T12:08:030org.subshare.crypto.GCMTest8.0830testEncryptionWithCipherfalsePASSED*** 0 *** *** 1 *** *** 2 *** *** 3 *** *** 4 *** *** 5 *** *** 6 *** *** 7 *** *** 8 *** *** 9 *** *** 10 *** *** 11 *** *** 12 *** *** 13 *** *** 14 *** *** 15 *** *** 16 *** *** 17 *** *** 18 *** *** 19 *** *** 20 *** *** 21 *** *** 22 *** *** 23 *** *** 24 *** *** 25 *** *** 26 *** *** 27 *** *** 28 *** *** 29 *** *** 30 *** *** 31 *** *** 32 *** *** 33 *** *** 34 *** *** 35 *** *** 36 *** *** 37 *** *** 38 *** *** 39 *** *** 40 *** *** 41 *** *** 42 *** *** 43 *** *** 44 *** *** 45 *** *** ...[truncated 296780 chars]... 70 *** *** gcm 9971 *** *** gcm 9972 *** *** gcm 9973 *** *** gcm 9974 *** *** gcm 9975 *** *** gcm 9976 *** *** gcm 9977 *** *** gcm 9978 *** *** gcm 9979 *** *** gcm 9980 *** *** gcm 9981 *** *** gcm 9982 *** *** gcm 9983 *** *** gcm 9984 *** *** gcm 9985 *** *** gcm 9986 *** *** gcm 9987 *** *** gcm 9988 *** *** gcm 9989 *** *** gcm 9990 *** *** gcm 9991 *** *** gcm 9992 *** *** gcm 9993 *** *** gcm 9994 *** *** gcm 9995 *** *** gcm 9996 *** *** gcm 9997 *** *** gcm 9998 *** *** gcm 9999 *** 0org.subshare.crypto.GCMTest2.6120testEncryptionWithBCLowLevelAPIfalsePASSED*** 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 *** 10.695org.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 *** 2022-02-02T12:08:040org.subshare.crypto.MACBenchmark0.3020testByteOrderfalsePASSED13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 0org.subshare.crypto.MACBenchmark12.1140benchmarkCreateManyInitialisedMACCalculatorsfalsePASSED13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 0org.subshare.crypto.MACBenchmark0.290benchmarkCreateManyUNinitialisedMACCalculatorsfalsePASSED13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 0org.subshare.crypto.MACBenchmark22.7420benchmarkCalculateManyMACsWithNewMACCalculatorfalsePASSED13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 0org.subshare.crypto.MACBenchmark15.2910benchmarkCalculateManyMACsWithSameMACCalculatorfalsePASSED13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 50.739002org.subshare.crypto.MACBenchmark13:08:05.111 [Test worker] INFO o.s.c.MACBenchmark - short1 = -18700 13:08:05.126 [Test worker] INFO o.s.c.MACBenchmark - short2 = -18700 13:08:05.129 [Test worker] INFO o.s.c.MACBenchmark - int1 = 46836 2022-02-02T12:08:040org.subshare.crypto.MACTest5.7160testAllSupportedMACsfalsePASSED5.716org.subshare.crypto.MACTest2022-02-02T12:08:030org.subshare.crypto.ShowSupportedAlgosForDocumentation3.0870showSupportedBlockCipherModesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.1320showSupportedCipherEnginesfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.1290showSupportedMACsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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.090showSupportedPaddingsfalsePASSED>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> *** 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 <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 3.4379997org.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 <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 2022-02-02T12:08:010org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest124.7510grantAndRevokeReadPermissionfalsePASSED13:09:33.028 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.342 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:35.503 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3ca691c]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibqk2-soa30-local' 13:09:35.719 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1988 ...[truncated 32856 chars]... INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 13:11:36.803 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 13:11:36.891 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 13:11:37.352 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3ca691c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:11:37.386 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 124.751org.subshare.local.CryptreeImplGrantRevokeReadPermissionTest13:09:33.028 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.342 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:35.503 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3ca691c]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibqk2-soa30-local' 13:09:35.719 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1988 ...[truncated 32856 chars]... INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 90.83% 13:11:36.803 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 96.55% 13:11:36.891 [Test worker] INFO o.s.l.CryptreeImplGrantRevokeReadPermissionTest - Local sync...: 100.00% 13:11:37.352 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3ca691c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:11:37.386 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:09:320org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest108.9340grantWritePermissionWithoutGrantPermissionfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest45.4620writeWithWritePermissionInParentParentfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest27.4390writeWithWritePermissionInParentfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest28.1030writeWithWritePermissionDirectDirfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest26.8230writeWithoutWritePermissionfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest25.3170writeWithWritePermissionInParentParentGrantedIndirectlyfalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest25.4310writeWithWritePermissionDirectFilefalsePASSED13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 287.50897org.subshare.local.CryptreeImplGrantRevokeWritePermissionTest13:09:32.292 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:32.601 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.820 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a0cb35]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibpx3-xbphe-local' 13:09:35.047 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1980 ...[truncated 149016 chars]... Test - Local sync...: 79.60% 13:14:19.308 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 90.83% 13:14:19.343 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 96.55% 13:14:19.385 [Test worker] INFO o.s.l.AbstractPermissionTest - Local sync...: 100.00% 13:14:19.419 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6daec3aa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:14:19.420 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:09:310org.subshare.local.FilePaddingLengthRandomTest8.1160testDistributionfalsePASSEDlengthCategory2HitCount: {_100K=300073, _1M=148146, _10M=46845, _100M=3010, _1G=1926, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 12928 0org.subshare.local.FilePaddingLengthRandomTest0.0210generateOneRandomLengthfalsePASSEDlengthCategory2HitCount: {_100K=300073, _1M=148146, _10M=46845, _100M=3010, _1G=1926, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 12928 8.137org.subshare.local.FilePaddingLengthRandomTestlengthCategory2HitCount: {_100K=300073, _1M=148146, _10M=46845, _100M=3010, _1G=1926, _10G=0} lengthCategory2HitPercentage: {_100K=600, _1M=296, _10M=94, _100M=6, _1G=4, _10G=0} 12928 2022-02-02T12:11:510org.subshare.local.db.DbMigrateFromDerbyToPostgresqlTest143.0630migrateFromDerbyToPostgresqlfalsePASSED13:09:32.858 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.021 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.984 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1a208b5d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibq1c-8g8d7-local' 13:09:35.237 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 197 ...[truncated 1327936 chars]... Objects: pcClass=org.subshare.local.persistence.UserRepoKeyPublicKeyReplacementRequestDeletion: *EMPTY* 13:11:55.578 [Test worker] INFO D.D.Retrieve - Object with id "1" not found ! 13:11:55.592 [Test worker] INFO c.c.c.l.d.DatabaseMigrater - migrateIfNeeded: localRoot='build/repo/kz5ibq1c-8g8d7-local': Migration complete! 13:11:55.614 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:11:55.909 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 143.063org.subshare.local.db.DbMigrateFromDerbyToPostgresqlTest13:09:32.858 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.021 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:34.984 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1a208b5d]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibq1c-8g8d7-local' 13:09:35.237 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 197 ...[truncated 1327936 chars]... Objects: pcClass=org.subshare.local.persistence.UserRepoKeyPublicKeyReplacementRequestDeletion: *EMPTY* 13:11:55.578 [Test worker] INFO D.D.Retrieve - Object with id "1" not found ! 13:11:55.592 [Test worker] INFO c.c.c.l.d.DatabaseMigrater - migrateIfNeeded: localRoot='build/repo/kz5ibq1c-8g8d7-local': Migration complete! 13:11:55.614 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:11:55.909 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 2022-02-02T12:09:320org.subshare.local.db.DbMigrateFromPostgresqlToDerbyTest136.9960migrateFromPostgresqlToDerbyfalsePASSED13:09:33.889 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.924 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:09:34.077 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:35.785 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2d6cd6eb]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibqs8-7jvfc-local' 13 ...[truncated 1487571 chars]... Objects: pcClass=org.subshare.local.persistence.UserRepoKeyPublicKeyReplacementRequestDeletion: *EMPTY* 13:11:50.903 [Test worker] INFO D.D.Retrieve - Object with id "1" not found ! 13:11:50.911 [Test worker] INFO c.c.c.l.d.DatabaseMigrater - migrateIfNeeded: localRoot='build/repo/kz5ibqs8-7jvfc-local': Migration complete! 13:11:50.935 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:11:51.295 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 136.996org.subshare.local.db.DbMigrateFromPostgresqlToDerbyTest13:09:33.889 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:33.924 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:09:34.077 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:35.785 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2d6cd6eb]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.local/build/repo/kz5ibqs8-7jvfc-local' 13 ...[truncated 1487571 chars]... Objects: pcClass=org.subshare.local.persistence.UserRepoKeyPublicKeyReplacementRequestDeletion: *EMPTY* 13:11:50.903 [Test worker] INFO D.D.Retrieve - Object with id "1" not found ! 13:11:50.911 [Test worker] INFO c.c.c.l.d.DatabaseMigrater - migrateIfNeeded: localRoot='build/repo/kz5ibqs8-7jvfc-local': Migration complete! 13:11:50.935 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:11:51.295 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 2022-02-02T12:09:330org.subshare.local.persistence.CollisionTest1.8410xorUids1falsePASSED0org.subshare.local.persistence.CollisionTest0.0110xorUids2falsePASSED1.852org.subshare.local.persistence.CollisionTest2022-02-02T12:09:300org.subshare.local.sign.SignableTest5.0150signAndSerializeAndVerifyfalsePASSED13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.4440signAndModifyCryptoKeyIdAndVerifyfalsePASSED13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1650signAndVerifyfalsePASSED13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.190signAndModifySignatureCreatedAndVerifyfalsePASSED13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignableTest0.1960signAndModifyCryptoRepoFileIdAndVerifyfalsePASSED13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 6.0099998org.subshare.local.sign.SignableTest13:09:33.143 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:09:34.580 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:09:34.711 [Test worker] INFO c.c.c.c.c.ConfigImpl - getLastModifiedAndWaitIfNeeded: Waiting 1984 ms. 13:09:36.732 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:09:36.733 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13 ...[truncated 904 chars]... o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.874 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:43.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.053 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.123 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.230 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:09:44.340 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:09:380org.subshare.local.sign.SignerVerifierStreamTest20.9780signRsaWithSha1falsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest0.5280signAndVerifySimpleBlockReadfalsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest22.4560sha1DigestfalsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest1.360signAndVerifyRandomBrokenfalsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest25.4840signAndVerifyRandomManySmallfalsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 0org.subshare.local.sign.SignerVerifierStreamTest5.0570signAndVerifyRandomfalsePASSED13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 75.863org.subshare.local.sign.SignerVerifierStreamTest13:11:55.984 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:11:55.985 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: dirString=${user.home}/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.GnuPgDir - getFile: result=/home/tomcat/.gnupg 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: secringFile='/home/tomcat/.gnupg/secring.gpg' 13:11:55.986 [Test worker] DEBUG o.s.c.p.g.BcWithLocalGnuPgPgp - load: pubringFile='/home/tomcat/.gnupg/ ...[truncated 4838 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 13:13:06.898 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:07.142 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:13:11.954 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 2022-02-02T12:11:560org.subshare.rest.client.transport.CryptreeRepoTransportTest39.7080encryptAndSignAndVerifyAndDecryptfalsePASSED13:14:27.469 [Test worker] INFO co.codewizards.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:14:27.916 [Test worker] DEBUG org.subshare.core.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:14:27.942 [Test worker] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 13:14:27.942 [Test worker] DEBUG co.codewizar ...[truncated 48764 chars]... 13:15:06.662 [Test worker] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 13:15:06.976 [Test worker] DEBUG org.subshare.rest.client.transport.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 13:15:06.976 [Test worker] DEBUG org.subshare.rest.client.transport.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 39.708org.subshare.rest.client.transport.CryptreeRepoTransportTest13:14:27.469 [Test worker] INFO co.codewizards.cloudstore.core.oio.OioRegistry - Preferred implementation 'IoFileFactory' for fileFactory 13:14:27.916 [Test worker] DEBUG org.subshare.core.pgp.gnupg.BcWithLocalGnuPgPgp - loadIfNeeded: invoking load(). 13:14:27.942 [Test worker] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.gnupg.dir' is not set (config is queried next). 13:14:27.942 [Test worker] DEBUG co.codewizar ...[truncated 48764 chars]... 13:15:06.662 [Test worker] DEBUG co.codewizards.cloudstore.core.config.ConfigImpl - getPropertyAsNonEmptyTrimmedString: System property with key='subshare.symmetricCipherTransformation' is not set (config is queried next). 13:15:06.976 [Test worker] DEBUG org.subshare.rest.client.transport.CryptreeRestRepoTransportImpl - close: There is no localRepoManager. 13:15:06.976 [Test worker] DEBUG org.subshare.rest.client.transport.CryptreeRestRepoTransportImpl - close: There is no restRepoTransport. 2022-02-02T12:14:270org.subshare.test.BackupRestoreRepoToRepoSyncIT241.0510backupRestoreClientRepofalsePASSED13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/repoRegistry.properties 13:44:47.975 [Test work ...[truncated 5235721 chars]... ynchronising...: 100.00% 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]getCloseDeferredMillis: closeDeferredMillis=10000 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]close: Deferring shut down of real LocalRepoManager 10000 ms. 13:48:49.026 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:48:50.072 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 4org.subshare.test.BackupRestoreRepoToRepoSyncIT0.051backupRestoreServerRepotrueSKIPPED13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/repoRegistry.properties 13:44:47.975 [Test work ...[truncated 5235721 chars]... ynchronising...: 100.00% 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]getCloseDeferredMillis: closeDeferredMillis=10000 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]close: Deferring shut down of real LocalRepoManager 10000 ms. 13:48:49.026 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:48:50.072 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 241.051org.subshare.test.BackupRestoreRepoToRepoSyncIT13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:44:47.974 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/repoRegistry.properties 13:44:47.975 [Test work ...[truncated 5235721 chars]... ynchronising...: 100.00% 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]getCloseDeferredMillis: closeDeferredMillis=10000 13:48:48.944 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5b140cec]close: Deferring shut down of real LocalRepoManager 10000 ms. 13:48:49.026 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:48:50.072 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 2022-02-02T12:44:470org.subshare.test.BrokenSignatureIT232.3060uploadBrokenSignaturefalsePASSED13:15:25.225 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.509 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/logback.client.xml 13:15:25.522 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 6166311 chars]... 71 common frames omitted 13:22:10.369 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]getCloseDeferredMillis: closeDeferredMillis=20000 13:22:10.370 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:22:10.371 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:11.466 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.BrokenSignatureIT144.5890downloadBrokenSignaturefalseFIXED13:15:25.225 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.509 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/logback.client.xml 13:15:25.522 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 6166311 chars]... 71 common frames omitted 13:22:10.369 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]getCloseDeferredMillis: closeDeferredMillis=20000 13:22:10.370 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:22:10.371 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:11.466 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 376.89502org.subshare.test.BrokenSignatureIT13:15:25.225 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.509 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/logback.client.xml 13:15:25.522 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 6166311 chars]... 71 common frames omitted 13:22:10.369 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]getCloseDeferredMillis: closeDeferredMillis=20000 13:22:10.370 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [36349e0d]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:22:10.371 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:11.466 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:15:530org.subshare.test.CollisionOnClientRepoToRepoSyncIT160.520newFileVsNewFileCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT237.0480newDirectoryVsNewDirectoryCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT195.1290modifiedFileVsDeletedFileCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT183.4350modifiedFileVsModifiedFileCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT138.5150deletedFileVsModifiedFileCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnClientRepoToRepoSyncIT122.8870newFileVsNewDirectoryCollisionOnClientfalsePASSED13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 1037.534org.subshare.test.CollisionOnClientRepoToRepoSyncIT13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:37:44.018 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:37:44.019 [Test worker] INFO ...[truncated 32619622 chars]... calRepoManager 20000 ms. 13:55:01.539 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]getCloseDeferredMillis: closeDeferredMillis=20000 13:55:01.540 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6a8dddc4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:55:01.551 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:02.552 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 2022-02-02T12:37:440org.subshare.test.CollisionOnServerRepoToRepoSyncIT407.9180newFileVsNewFileUploadedCollisionOnServerfalsePASSED13:15:25.118 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.433 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/logback.client.xml 13:15:25.464 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 16745209 chars]... calRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]getCloseDeferredMillis: closeDeferredMillis=20000 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:34:15.818 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.CollisionOnServerRepoToRepoSyncIT690.8520newFileVsNewFileUploadingCollisionOnServerfalsePASSED13:15:25.118 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.433 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/logback.client.xml 13:15:25.464 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 16745209 chars]... calRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]getCloseDeferredMillis: closeDeferredMillis=20000 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:34:15.818 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 1098.77org.subshare.test.CollisionOnServerRepoToRepoSyncIT13:15:25.118 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.433 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/logback.client.xml 13:15:25.464 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 16745209 chars]... calRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]getCloseDeferredMillis: closeDeferredMillis=20000 13:34:14.385 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5f6e9f40]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:34:14.385 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:34:15.818 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:15:550org.subshare.test.IgnoreRuleRepoToRepoSyncIT378.3510ignoreRulesAddedAfterFilesSyncedfalsePASSED13:15:25.224 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.639 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/logback.client.xml 13:15:25.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 17969406 chars]... calRepoManager 20000 ms. 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]getCloseDeferredMillis: closeDeferredMillis=20000 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:30:01.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:30:02.412 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT248.9120ignoreRulesBecomeDisabledfalsePASSED13:15:25.224 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.639 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/logback.client.xml 13:15:25.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 17969406 chars]... calRepoManager 20000 ms. 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]getCloseDeferredMillis: closeDeferredMillis=20000 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:30:01.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:30:02.412 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 0org.subshare.test.IgnoreRuleRepoToRepoSyncIT216.4080ignoreRulesExistBeforeAffectedFilesfalsePASSED13:15:25.224 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.639 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/logback.client.xml 13:15:25.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 17969406 chars]... calRepoManager 20000 ms. 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]getCloseDeferredMillis: closeDeferredMillis=20000 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:30:01.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:30:02.412 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 843.671org.subshare.test.IgnoreRuleRepoToRepoSyncIT13:15:25.224 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.639 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/logback.client.xml 13:15:25.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 17969406 chars]... calRepoManager 20000 ms. 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]getCloseDeferredMillis: closeDeferredMillis=20000 13:30:01.145 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [396973fa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:30:01.148 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:30:02.412 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 2022-02-02T12:15:570org.subshare.test.InterruptedRepoToRepoSyncIT158.7820interruptSyncDownAndResumefalsePASSED13:34:15.848 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:34:15.849 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:34:15.849 [Test worker] INFO ...[truncated 8923852 chars]... calRepoManager 20000 ms. 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]getCloseDeferredMillis: closeDeferredMillis=20000 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:39:28.304 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:39:29.389 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.InterruptedRepoToRepoSyncIT153.440interruptSyncUpAndResumefalsePASSED13:34:15.848 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:34:15.849 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:34:15.849 [Test worker] INFO ...[truncated 8923852 chars]... calRepoManager 20000 ms. 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]getCloseDeferredMillis: closeDeferredMillis=20000 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:39:28.304 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:39:29.389 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 312.222org.subshare.test.InterruptedRepoToRepoSyncIT13:34:15.848 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:34:15.849 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:34:15.849 [Test worker] INFO ...[truncated 8923852 chars]... calRepoManager 20000 ms. 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]getCloseDeferredMillis: closeDeferredMillis=20000 13:39:28.303 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [54c71e75]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:39:28.304 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:39:29.389 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:34:160org.subshare.test.InviteUserAndSyncIT158.8880inviteUserAndSync_singleReadPermissionOnSubdirfalsePASSED13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/serverRepoRegistry.subshare 13:42:09.669 [Test ...[truncated 16492505 chars]... LocalRepoManager 20000 ms. 13:51:51.582 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:51.583 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:51.583 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:52.181 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.InviteUserAndSyncIT235.1240inviteUserAndSync_singleWritePermissionOnRootfalsePASSED13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/serverRepoRegistry.subshare 13:42:09.669 [Test ...[truncated 16492505 chars]... LocalRepoManager 20000 ms. 13:51:51.582 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:51.583 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:51.583 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:52.181 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.InviteUserAndSyncIT187.9010inviteUserAndSync_singleReadPermissionOnRootfalsePASSED13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/serverRepoRegistry.subshare 13:42:09.669 [Test ...[truncated 16492505 chars]... LocalRepoManager 20000 ms. 13:51:51.582 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:51.583 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:51.583 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:52.181 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 581.91296org.subshare.test.InviteUserAndSyncIT13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:42:09.669 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/serverRepoRegistry.subshare 13:42:09.669 [Test ...[truncated 16492505 chars]... LocalRepoManager 20000 ms. 13:51:51.582 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:51.583 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [601deb4]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:51.583 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:52.181 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 2022-02-02T12:42:090org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT319.1340inviteUserAndSync_withMetaOnly_singleWritePermissionOnRootfalsePASSED13:26:46.317 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/localServer.properties 13:26:46.317 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:26:46.317 [Test worker] INFO ...[truncated 6937157 chars]... LocalRepoManager 20000 ms. 13:32:05.448 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6fb7e5c]getCloseDeferredMillis: closeDeferredMillis=20000 13:32:05.448 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6fb7e5c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:32:05.451 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:06.246 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 319.134org.subshare.test.InviteUserWithNormalAndMetaOnlyRepoIT13:26:46.317 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/localServer.properties 13:26:46.317 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:26:46.317 [Test worker] INFO ...[truncated 6937157 chars]... LocalRepoManager 20000 ms. 13:32:05.448 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6fb7e5c]getCloseDeferredMillis: closeDeferredMillis=20000 13:32:05.448 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [6fb7e5c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:32:05.451 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:06.246 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 2022-02-02T12:26:460org.subshare.test.Issue4IT127.8490issue_4_syncFileDeletedAfterLocalSyncfalsePASSED13:22:11.524 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:22:11.525 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:22:11.525 [Test worker] INFO ...[truncated 2975947 chars]... ynchronising...: 100.00% 13:24:19.376 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4f1255a7]getCloseDeferredMillis: closeDeferredMillis=20000 13:24:19.376 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4f1255a7]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:24:19.377 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:24:20.561 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 127.849org.subshare.test.Issue4IT13:22:11.524 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:22:11.525 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:22:11.525 [Test worker] INFO ...[truncated 2975947 chars]... ynchronising...: 100.00% 13:24:19.376 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4f1255a7]getCloseDeferredMillis: closeDeferredMillis=20000 13:24:19.376 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4f1255a7]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:24:19.377 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:24:20.561 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:22:110org.subshare.test.Issue5IT422.7120issue_5_inviteMultipleFriendsfalsePASSED13:15:25.622 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.993 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/logback.client.xml 13:15:26.030 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 8000562 chars]... calRepoManager 20000 ms. 13:22:54.731 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [69fd6671]getCloseDeferredMillis: closeDeferredMillis=20000 13:22:54.731 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [69fd6671]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:22:54.732 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:55.261 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 422.712org.subshare.test.Issue5IT13:15:25.622 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.993 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/logback.client.xml 13:15:26.030 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 8000562 chars]... calRepoManager 20000 ms. 13:22:54.731 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [69fd6671]getCloseDeferredMillis: closeDeferredMillis=20000 13:22:54.731 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [69fd6671]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:22:54.732 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:55.261 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 2022-02-02T12:15:520org.subshare.test.Issue8IT457.6490issue_8_threesomeWithFreshmenfalsePASSED13:30:02.454 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:30:02.454 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:30:02.454 [Test worker] INFO ...[truncated 11673909 chars]... - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE ("a0"."discriminator" = 'LocalRepository' OR "a0"."discriminator" = 'SsLocalRepository') 13:37:40.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2992046c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:37:43.990 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 457.649org.subshare.test.Issue8IT13:30:02.454 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:30:02.454 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:30:02.454 [Test worker] INFO ...[truncated 11673909 chars]... - SELECT "a0"."changed","a0"."created","a0"."id","a0"."repositoryid","a0"."revision","a0"."discriminator" FROM "repository" "a0" WHERE ("a0"."discriminator" = 'LocalRepository' OR "a0"."discriminator" = 'SsLocalRepository') 13:37:40.100 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2992046c]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:37:43.990 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 2022-02-02T12:30:020org.subshare.test.LocalServerClientIT0.8090invokeSimpleStaticMethodfalsePASSED13:22:55.294 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:22:55.735 [Test worker] INFO c.c.c.l.s.LocalServer - logMemoryStats: max=512.0 MiB, allocated=157.0 MiB, used=81.5 MiB, available=430.5 MiB 13:22:56.806 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:57.586 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:58.150 [Test worker] INFO o.s.c.p.PgpRegistry - clearCach ...[truncated 64995 chars]... er(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:23:01.380 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:12.509 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.LocalServerClientIT3.7810invokeDeniedMethodsfalsePASSED13:22:55.294 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:22:55.735 [Test worker] INFO c.c.c.l.s.LocalServer - logMemoryStats: max=512.0 MiB, allocated=157.0 MiB, used=81.5 MiB, available=430.5 MiB 13:22:56.806 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:57.586 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:58.150 [Test worker] INFO o.s.c.p.PgpRegistry - clearCach ...[truncated 64995 chars]... er(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:23:01.380 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:12.509 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 4.59org.subshare.test.LocalServerClientIT13:22:55.294 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:22:55.735 [Test worker] INFO c.c.c.l.s.LocalServer - logMemoryStats: max=512.0 MiB, allocated=157.0 MiB, used=81.5 MiB, available=430.5 MiB 13:22:56.806 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:57.586 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:22:58.150 [Test worker] INFO o.s.c.p.PgpRegistry - clearCach ...[truncated 64995 chars]... er(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56) at java.base/java.lang.Thread.run(Thread.java:834) 13:23:01.380 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:12.509 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 2022-02-02T12:22:560org.subshare.test.LockerSyncIT19.4660syncServerRegistryfalsePASSED13:41:33.968 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:41:33.969 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/repoRegistry.properties 13:41:33.970 [Test work ...[truncated 177042 chars]... eyIds=[a58da7d810b74edf] 13:42:06.660 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 13:42:07.168 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: starting try 2 of 3 13:42:07.170 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - determineBaseUrl: hostURL='https://server1.domain.tld:12345/': entered. 13:42:09.633 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.LockerSyncIT13.2620syncUserRegistryfalsePASSED13:41:33.968 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:41:33.969 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/repoRegistry.properties 13:41:33.970 [Test work ...[truncated 177042 chars]... eyIds=[a58da7d810b74edf] 13:42:06.660 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 13:42:07.168 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: starting try 2 of 3 13:42:07.170 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - determineBaseUrl: hostURL='https://server1.domain.tld:12345/': entered. 13:42:09.633 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 32.728org.subshare.test.LockerSyncIT13:41:33.968 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/ssl.client 13:41:33.969 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/2QjEb3eUMiqwok69VH-xag/.subshare/repoRegistry.properties 13:41:33.970 [Test work ...[truncated 177042 chars]... eyIds=[a58da7d810b74edf] 13:42:06.660 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 13:42:07.168 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: starting try 2 of 3 13:42:07.170 [Timer-3] DEBUG c.c.c.r.c.CloudStoreRestClient - determineBaseUrl: hostURL='https://server1.domain.tld:12345/': entered. 13:42:09.633 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 2022-02-02T12:41:330org.subshare.test.MetaOnlyRepoSyncIT305.6620syncFromLocalToRemoteToMetaOnlyAddingFilesfalsePASSED13:15:25.430 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.813 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/logback.client.xml 13:15:25.846 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 11341512 chars]... calRepoManager 20000 ms. 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]getCloseDeferredMillis: closeDeferredMillis=20000 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:26:45.313 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:26:46.273 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 0org.subshare.test.MetaOnlyRepoSyncIT160.8760syncFromLocalToRemoteToMetaOnlyfalsePASSED13:15:25.430 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.813 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/logback.client.xml 13:15:25.846 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 11341512 chars]... calRepoManager 20000 ms. 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]getCloseDeferredMillis: closeDeferredMillis=20000 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:26:45.313 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:26:46.273 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 0org.subshare.test.MetaOnlyRepoSyncIT183.2070syncFromLocalToRemoteToMetaOnlyRemovingFilesfalsePASSED13:15:25.430 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.813 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/logback.client.xml 13:15:25.846 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 11341512 chars]... calRepoManager 20000 ms. 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]getCloseDeferredMillis: closeDeferredMillis=20000 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:26:45.313 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:26:46.273 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 649.745org.subshare.test.MetaOnlyRepoSyncIT13:15:25.430 [Test worker] INFO c.c.c.c.o.OioRegistry - Preferred implementation 'NioFileFactory' for fileFactory 13:15:25.813 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/logback.client.xml 13:15:25.846 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshar ...[truncated 11341512 chars]... calRepoManager 20000 ms. 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]getCloseDeferredMillis: closeDeferredMillis=20000 13:26:45.312 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7a8f9193]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:26:45.313 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:26:46.273 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 2022-02-02T12:15:550org.subshare.test.MultiplePgpKeysSingleUserRegistryIT3.5590multiplePgpKeysSingleUserRegistryfalsePASSED13:58:07.747 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:58:07.747 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:58:07.747 [Test worker] INFO ...[truncated 4285 chars]... rRepoKey: pgpKey=PgpKey[bbef20af171556db, [Alexander Bieber (TEST) <alex@nightlabs.de>]], userRepoKey=UserRepoKeyImpl[userRepoKeyId=i643InMKhtWH0W53evVz6Q, invitation=false] 13:58:11.240 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 13:58:11.305 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:11.756 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 3.559org.subshare.test.MultiplePgpKeysSingleUserRegistryIT13:58:07.747 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:58:07.747 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:58:07.747 [Test worker] INFO ...[truncated 4285 chars]... rRepoKey: pgpKey=PgpKey[bbef20af171556db, [Alexander Bieber (TEST) <alex@nightlabs.de>]], userRepoKey=UserRepoKeyImpl[userRepoKeyId=i643InMKhtWH0W53evVz6Q, invitation=false] 13:58:11.240 [Test worker] DEBUG o.s.c.p.g.BcPgpDecoder - verifySignature: signingPgpKeyIds=[d7a92a24aa97ddbd] 13:58:11.305 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:11.756 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:58:070org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT268.1590parentConfigWithRemotePathPrefixfalsePASSED13:24:20.795 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:24:20.799 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:24:20.800 [Test worker] INFO ...[truncated 8119959 chars]... calRepoManager 20000 ms. 13:28:48.958 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f6ad4f0]getCloseDeferredMillis: closeDeferredMillis=20000 13:28:48.958 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f6ad4f0]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:28:48.959 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:28:50.207 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 268.159org.subshare.test.PathPrefixedConfigInheritanceRepoToRepoSyncIT13:24:20.795 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:24:20.799 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:24:20.800 [Test worker] INFO ...[truncated 8119959 chars]... calRepoManager 20000 ms. 13:28:48.958 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f6ad4f0]getCloseDeferredMillis: closeDeferredMillis=20000 13:28:48.958 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f6ad4f0]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:28:48.959 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:28:50.207 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:24:200org.subshare.test.PermissionIT202.8110interruptPermissionSetInheritancefalsePASSED13:32:06.269 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].beforeClass: entered. testInstanceCounter=1 13:32:06.275 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:07.367 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1d3a]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo/kz5j4ptu-yvz7n-local-src' 13:32:07.371 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1 ...[truncated 20909207 chars]... calRepoManager 20000 ms. 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]getCloseDeferredMillis: closeDeferredMillis=20000 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:44:46.900 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:44:47.938 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PermissionIT272.3420uploadBackdatedSignaturefalsePASSED13:32:06.269 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].beforeClass: entered. testInstanceCounter=1 13:32:06.275 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:07.367 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1d3a]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo/kz5j4ptu-yvz7n-local-src' 13:32:07.371 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1 ...[truncated 20909207 chars]... calRepoManager 20000 ms. 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]getCloseDeferredMillis: closeDeferredMillis=20000 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:44:46.900 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:44:47.938 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PermissionIT285.4780nonOwnerAdminGrantsWritePermissionfalsePASSED13:32:06.269 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].beforeClass: entered. testInstanceCounter=1 13:32:06.275 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:07.367 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1d3a]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo/kz5j4ptu-yvz7n-local-src' 13:32:07.371 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1 ...[truncated 20909207 chars]... calRepoManager 20000 ms. 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]getCloseDeferredMillis: closeDeferredMillis=20000 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:44:46.900 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:44:47.938 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 760.631org.subshare.test.PermissionIT13:32:06.269 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].beforeClass: entered. testInstanceCounter=1 13:32:06.275 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:32:07.367 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1d3a]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo/kz5j4ptu-yvz7n-local-src' 13:32:07.371 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [1cef1 ...[truncated 20909207 chars]... calRepoManager 20000 ms. 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]getCloseDeferredMillis: closeDeferredMillis=20000 13:44:46.899 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [2c81bcfa]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:44:46.900 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:44:47.938 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 2022-02-02T12:32:060org.subshare.test.PgpSyncIT2.4850testPgpSyncfalsePASSED13:55:02.584 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:55:02.584 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:55:02.584 [Test worker] INFO ...[truncated 3196 chars]... 55:06.507 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: invocation took 9 ms 13:55:06.508 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: starting try 1 of 3 13:55:06.547 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: invocation took 39 ms 13:55:06.573 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:07.043 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 2.485org.subshare.test.PgpSyncIT13:55:02.584 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/localServer.properties 13:55:02.584 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/D5P-3n2GSaEOTPNttrTy5w/.subshare/ssl.client 13:55:02.584 [Test worker] INFO ...[truncated 3196 chars]... 55:06.507 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: invocation took 9 ms 13:55:06.508 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: starting try 1 of 3 13:55:06.547 [Test worker] DEBUG c.c.c.r.c.CloudStoreRestClient - execute: invocation took 39 ms 13:55:06.573 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:55:07.043 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [vFZLdb-oY8DxssYQgWhC4w].afterClass: entered. testInstanceCounter=1 2022-02-02T12:55:040org.subshare.test.PostgresqlRepoToRepoSyncIT148.5050syncFromLocalToRemoteToLocalAfterCreateAndDeleteFilefalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT204.0740syncFromLocalToRemoteToLocalWithPathPrefixWithWritePermissionGrantedAndRevokedfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT123.680syncFromLocalToRemoteToLocalfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT79.1220syncFromLocalToRemoteToLocalWithPathPrefixWithoutSubdirClearanceKeyfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT90.7540syncFromLocalToRemoteToLocalWithPathPrefixfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT121.7440syncFromLocalToRemoteToLocalThenDeleteFileAndSyncAgainfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT195.2620syncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 0org.subshare.test.PostgresqlRepoToRepoSyncIT136.7680multiSyncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 4org.subshare.test.PostgresqlRepoToRepoSyncIT0.051syncFromLocalToRemoteToLocalThenCauseDeleteCollisionOnServerDuringUpSynctrueSKIPPED13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 1099.9089org.subshare.test.PostgresqlRepoToRepoSyncIT13:23:12.948 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].beforeClass: entered. testInstanceCounter=1 13:23:12.951 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:23:12.957 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:23:14.215 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [5fe5923b]<init>: localRoot='/home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/repo ...[truncated 37489346 chars]... 5]getCloseDeferredMillis: closeDeferredMillis=20000 13:41:32.861 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3f0b7f25]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:41:32.861 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:41:33.890 [Test worker] INFO c.c.c.l.d.DatabaseAdapterFactoryRegistry - clearCache: entered. 13:41:33.892 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [BNSLGJXx60wtQ1Mven5B_Q].afterClass: entered. testInstanceCounter=1 2022-02-02T12:23:120org.subshare.test.ReadUserIdentityIT257.230inviteWithReadInvitationAndGrantReadUserIdentityPermissionLaterfalsePASSED13:28:50.252 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:28:50.253 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:28:50.253 [Test worker] INFO ...[truncated 6660592 chars]... calRepoManager 20000 ms. 13:33:07.483 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7de3ade5]getCloseDeferredMillis: closeDeferredMillis=20000 13:33:07.483 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7de3ade5]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:33:07.484 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:33:08.019 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 257.23org.subshare.test.ReadUserIdentityIT13:28:50.252 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/localServer.properties 13:28:50.253 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/PTVmNI9XILYtFOFbWe_Nkw/.subshare/ssl.client 13:28:50.253 [Test worker] INFO ...[truncated 6660592 chars]... calRepoManager 20000 ms. 13:33:07.483 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7de3ade5]getCloseDeferredMillis: closeDeferredMillis=20000 13:33:07.483 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [7de3ade5]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:33:07.484 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:33:08.019 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [brMnYJ-NyXzp_eYilA71wg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:28:500org.subshare.test.RepoToRepoSyncIT166.30syncFromLocalToRemoteToLocalAfterCreateAndDeleteFilefalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT201.5730syncFromLocalToRemoteToLocalWithPathPrefixWithWritePermissionGrantedAndRevokedfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT158.4830syncFromLocalToRemoteToLocalfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT111.0620syncFromLocalToRemoteToLocalWithPathPrefixWithoutSubdirClearanceKeyfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT115.0630syncFromLocalToRemoteToLocalWithPathPrefixfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT115.9130syncFromLocalToRemoteToLocalThenDeleteFileAndSyncAgainfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT150.7150syncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 4org.subshare.test.RepoToRepoSyncIT0.051syncFromLocalToRemoteToLocalThenCauseDeleteCollisionOnServerDuringUpSynctrueSKIPPED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 0org.subshare.test.RepoToRepoSyncIT98.340multiSyncFromLocalToRemoteToLocalWithPathPrefixWithSubdirClearanceKeyfalsePASSED13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 1117.449org.subshare.test.RepoToRepoSyncIT13:39:29.418 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/localServer.properties 13:39:29.419 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/5vcr82IfuLgDqvw1kIUr2g/.subshare/ssl.client 13:39:29.419 [Test worker] INFO ...[truncated 42252799 chars]... calRepoManager 20000 ms. 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]getCloseDeferredMillis: closeDeferredMillis=20000 13:58:06.870 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [4ce84e80]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:58:06.871 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:58:07.733 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [aP3mK45JfFOOOv585hHHvg].afterClass: entered. testInstanceCounter=1 2022-02-02T12:39:290org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT176.8840newFileVsNewSymlinkCollisionOnClientfalsePASSED13:48:50.101 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:48:50.101 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/repoRegistry.properties 13:48:50.101 [Test work ...[truncated 5216566 chars]... calRepoManager 20000 ms. 13:51:46.978 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3fb382e2]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:46.978 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3fb382e2]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:46.984 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:48.169 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 176.884org.subshare.test.SymlinkCollisionOnClientRepoToRepoSyncIT13:48:50.101 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: NOT deleting: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/ssl.client 13:48:50.101 [Test worker] INFO o.s.t.AbstractIT - abstractIT_beforeClass: Deleting recursively: /home/tomcat/.jenkins/workspace/org.subshare_java_11/org.subshare/org.subshare.test/build/jvm/V7rijKDptmRAH-wXTZOScw/.subshare/repoRegistry.properties 13:48:50.101 [Test work ...[truncated 5216566 chars]... calRepoManager 20000 ms. 13:51:46.978 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3fb382e2]getCloseDeferredMillis: closeDeferredMillis=20000 13:51:46.978 [Test worker] INFO c.c.c.l.LocalRepoManagerImpl - [3fb382e2]close: Deferring shut down of real LocalRepoManager 20000 ms. 13:51:46.984 [Test worker] INFO o.s.c.p.PgpRegistry - clearCache: entered. 13:51:48.169 [Test worker] DEBUG c.c.c.t.CloudStoreServerTestSupport - [bgQ9mbeH4keacP7zBkjCwA].afterClass: entered. testInstanceCounter=1 2022-02-02T12:48:50