SuccessConsole Output

[INFO]                                                                         
[INFO] ------------------------------------------------------------------------
[INFO] Building org.cumulus4j.keystore 1.2.1-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO] 
[INFO] --- maven-clean-plugin:2.4.1:clean (default-clean) @ org.cumulus4j.keystore ---
[INFO] Deleting /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target
[INFO] Deleting /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore (includes = [*.log, $jarplug.work/**], excludes = [])
[INFO] 
[INFO] --- maven-resources-plugin:2.5:resources (default-resources) @ org.cumulus4j.keystore ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/src/main/resources
[INFO] Copying 1 resource
[INFO] Copying 0 resource
[INFO] 
[INFO] --- maven-compiler-plugin:2.3.2:compile (default-compile) @ org.cumulus4j.keystore ---
[INFO] Compiling 27 source files to /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/classes
[INFO] 
[INFO] --- maven-resources-plugin:2.5:testResources (default-testResources) @ org.cumulus4j.keystore ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 19 resources
[INFO] 
[INFO] --- maven-compiler-plugin:2.3.2:testCompile (default-testCompile) @ org.cumulus4j.keystore ---
[INFO] Compiling 12 source files to /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/test-classes
[INFO] 
[INFO] --- maven-surefire-plugin:2.8.1:test (default-test) @ org.cumulus4j.keystore ---
[INFO] Surefire report directory: /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/surefire-reports

-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running org.cumulus4j.keystore.test.KeyStorePropertyTest
09:50:19,278 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:19,281 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:19,284 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:19,644 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
09:50:19,644 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
09:50:19,645 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
09:50:19,646 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
09:50:19,647 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
09:50:19,647 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
09:50:19,648 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
09:50:19,648 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
09:50:19,649 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
09:50:19,649 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
09:50:19,651 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3WITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
09:50:19,651 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MACWITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
09:50:19,653 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
09:50:19,653 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
09:50:19,654 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
09:50:19,654 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
09:50:19,656 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
09:50:19,656 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
09:50:19,658 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA384"
09:50:19,659 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA512"
09:50:19,661 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
09:50:19,661 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
09:50:19,661 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
09:50:19,662 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
09:50:19,662 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
09:50:19,663 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
09:50:19,664 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
09:50:19,664 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
09:50:19,664 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
09:50:19,665 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
09:50:19,666 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
09:50:19,666 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
09:50:19,667 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
09:50:19,667 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
09:50:19,667 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
09:50:19,668 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
09:50:19,669 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
09:50:19,669 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
09:50:19,670 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
09:50:19,670 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
09:50:19,670 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
09:50:19,671 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
09:50:19,672 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
09:50:19,672 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
09:50:19,673 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
09:50:19,673 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
09:50:19,673 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
09:50:19,675 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
09:50:19,675 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
09:50:19,675 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
09:50:19,676 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACTIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
09:50:19,677 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
09:50:19,677 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
09:50:19,683 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="ElGamal" engineClass="org.bouncycastle.crypto.engines.ElGamalEngine"
09:50:19,689 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="NaccacheStern" engineClass="org.bouncycastle.crypto.engines.NaccacheSternEngine"
09:50:19,693 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="RSA" engineClass="org.bouncycastle.crypto.engines.RSABlindedEngine"
09:50:19,701 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES" engineClass="org.bouncycastle.crypto.engines.AESEngine"
09:50:19,711 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.fast" engineClass="org.bouncycastle.crypto.engines.AESFastEngine"
09:50:19,716 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.light" engineClass="org.bouncycastle.crypto.engines.AESLightEngine"
09:50:19,722 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Blowfish" engineClass="org.bouncycastle.crypto.engines.BlowfishEngine"
09:50:19,728 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia" engineClass="org.bouncycastle.crypto.engines.CamelliaEngine"
09:50:19,737 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia.light" engineClass="org.bouncycastle.crypto.engines.CamelliaLightEngine"
09:50:19,747 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST5" engineClass="org.bouncycastle.crypto.engines.CAST5Engine"
09:50:19,750 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST6" engineClass="org.bouncycastle.crypto.engines.CAST6Engine"
09:50:19,755 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DESede" engineClass="org.bouncycastle.crypto.engines.DESedeEngine"
09:50:19,756 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DES" engineClass="org.bouncycastle.crypto.engines.DESEngine"
09:50:19,763 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GOST28147" engineClass="org.bouncycastle.crypto.engines.GOST28147Engine"
09:50:19,765 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Noekeon" engineClass="org.bouncycastle.crypto.engines.NoekeonEngine"
09:50:19,767 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Null" engineClass="org.bouncycastle.crypto.engines.NullEngine"
09:50:19,772 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC2" engineClass="org.bouncycastle.crypto.engines.RC2Engine"
09:50:19,776 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-32" engineClass="org.bouncycastle.crypto.engines.RC532Engine"
09:50:19,778 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-64" engineClass="org.bouncycastle.crypto.engines.RC564Engine"
09:50:19,780 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC6" engineClass="org.bouncycastle.crypto.engines.RC6Engine"
09:50:19,787 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Rijndael" engineClass="org.bouncycastle.crypto.engines.RijndaelEngine"
09:50:19,792 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SEED" engineClass="org.bouncycastle.crypto.engines.SEEDEngine"
09:50:19,799 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Serpent" engineClass="org.bouncycastle.crypto.engines.SerpentEngine"
09:50:19,801 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SKIPJACK" engineClass="org.bouncycastle.crypto.engines.SkipjackEngine"
09:50:19,803 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="TEA" engineClass="org.bouncycastle.crypto.engines.TEAEngine"
09:50:19,809 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Twofish" engineClass="org.bouncycastle.crypto.engines.TwofishEngine"
09:50:19,811 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="XTEA" engineClass="org.bouncycastle.crypto.engines.XTEAEngine"
09:50:19,815 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Grain-128" engineClass="org.bouncycastle.crypto.engines.Grain128Engine"
09:50:19,817 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GRAIN-V1" engineClass="org.bouncycastle.crypto.engines.Grainv1Engine"
09:50:19,820 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-128" engineClass="org.bouncycastle.crypto.engines.HC128Engine"
09:50:19,822 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-256" engineClass="org.bouncycastle.crypto.engines.HC256Engine"
09:50:19,824 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="ISAAC" engineClass="org.bouncycastle.crypto.engines.ISAACEngine"
09:50:19,826 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC4" engineClass="org.bouncycastle.crypto.engines.RC4Engine"
09:50:19,833 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Salsa20" engineClass="org.bouncycastle.crypto.engines.Salsa20Engine"
09:50:19,835 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CBC" modeClass="org.bouncycastle.crypto.modes.CBCBlockCipher"
09:50:19,837 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="CCM" modeClass="org.bouncycastle.crypto.modes.CCMBlockCipher"
09:50:19,841 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,841 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,841 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,841 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,842 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,843 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,844 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,845 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,845 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
09:50:19,846 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CTS" modeClass="org.bouncycastle.crypto.modes.CTSBlockCipher"
09:50:19,847 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CBC-CTS" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCBCCTSBlockCipher"
09:50:19,849 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="EAX" modeClass="org.bouncycastle.crypto.modes.EAXBlockCipher"
09:50:19,851 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="GCM" modeClass="org.bouncycastle.crypto.modes.GCMBlockCipher"
09:50:19,852 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="GOFB" modeClass="org.bouncycastle.crypto.modes.GOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,855 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,856 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,857 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,858 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,859 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
09:50:19,860 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="SIC" modeClass="org.bouncycastle.crypto.modes.SICBlockCipher"
09:50:19,886 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126-2" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
09:50:19,886 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
09:50:19,887 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO7816-4" paddingClass="org.bouncycastle.crypto.paddings.ISO7816d4Padding"
09:50:19,889 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS7" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
09:50:19,889 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS5" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
09:50:19,891 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="TBC" paddingClass="org.bouncycastle.crypto.paddings.TBCPadding"
09:50:19,893 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="X9.23" paddingClass="org.bouncycastle.crypto.paddings.X923Padding"
09:50:19,894 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ZeroByte" paddingClass="org.bouncycastle.crypto.paddings.ZeroBytePadding"
09:50:19,896 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="ISO9796-1" paddingClass="org.bouncycastle.crypto.encodings.ISO9796d1Encoding"
09:50:19,897 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEP" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
09:50:19,898 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEPWITHSHA1ANDMGF1" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
09:50:19,899 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="PKCS1" paddingClass="org.bouncycastle.crypto.encodings.PKCS1Encoding"
09:50:19,916 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DH" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DHBasicKeyPairGeneratorFactory"
09:50:19,917 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DSAKeyPairGeneratorFactory"
09:50:19,918 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="ElGamal" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.ElGamalKeyPairGeneratorFactory"
09:50:19,920 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="GOST3410" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.GOST3410KeyPairGeneratorFactory"
09:50:19,921 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="NaccacheStern" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.NaccacheSternKeyPairGeneratorFactory"
09:50:19,925 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="RSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.RSAKeyPairGeneratorFactory"
09:50:19,938 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:19,996 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:19,996 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:19,996 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:20,025 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:20,052 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:20,052 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:20,052 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:20,071 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:20,127 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:20,128 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:20,128 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:20,137 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:20,171 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:20,171 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:20,172 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:20,183 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.027 sec
Running org.cumulus4j.keystore.test.DateDependentKeyStrategyTest
09:50:20,237 DEBUG [main][DateDependentKeyStrategy] init: Calculated numberOfKeysToGenerate=5761
09:50:20,238 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:20,238 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:20,238 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:20,247 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:20,327 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@50b7ad1a
09:50:20,327 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,327 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ac5af5c
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@787be75d
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e92c877
09:50:20,328 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e92c877
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46a7041a
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7efe6b32
09:50:20,328 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,328 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46a7041a
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@712fe0c0
09:50:20,329 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@712fe0c0
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7e4c974d
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56618102
09:50:20,329 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7e4c974d
09:50:20,329 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1b5cd69c
09:50:20,330 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,330 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1b5cd69c
09:50:20,330 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c0da9cc
09:50:20,330 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7506c77f
09:50:20,330 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,330 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c0da9cc
09:50:20,330 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10139edf
09:50:20,340 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@78234ab7
09:50:20,341 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:20,341 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10139edf
09:50:20,341 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ac5af5c
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.172 sec
Running org.cumulus4j.keystore.test.KeyStoreUserTest
09:50:24,430 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,431 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,431 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,441 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,460 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,460 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3380e475
09:50:24,463 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,464 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,464 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,470 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,509 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "bieber"!
09:50:24,509 WARN  [main][KeyStore] getMasterKey: Wrong password for user "bieber"! MAC verification failed.
09:50:24,513 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,513 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,514 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,520 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,548 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
09:50:24,549 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:50:24,551 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,551 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,552 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,557 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,572 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,573 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,573 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,579 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,581 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,582 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,582 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,588 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,588 WARN  [main][KeyStore] getMasterKey: Unknown userName: null
09:50:24,591 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,591 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,591 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,597 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,603 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
09:50:24,604 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:50:24,606 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,606 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,606 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,612 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,631 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,631 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@519f8603
09:50:24,633 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,633 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,634 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:50:24,639 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:50:24,658 DEBUG [main][CachedMasterKey] clear: Clearing for userName='bieber'.
09:50:24,658 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@401758d2
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.246 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_AES_CBC_PKCS5Padding_Test
09:50:24,680 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
09:50:24,683 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,683 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,683 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:24,693 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:24,706 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
09:50:24,706 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:50:24,709 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,709 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,709 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:24,715 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:24,723 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,724 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@23852c7f
09:50:24,730 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,731 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c2c5810
09:50:24,740 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,740 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4349c220
09:50:24,747 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,747 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f67e563
09:50:24,757 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:24,757 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5e04d6b0
09:50:24,771 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,771 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,771 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:24,777 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:24,786 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:24,787 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:24,787 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,801 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:24,802 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:24,802 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:24,808 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:47,375 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
09:50:47,375 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3e20e8c4
09:50:47,376 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53622a11
09:50:47,377 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3380e475
09:50:47,378 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3e20e8c4
09:50:47,379 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
09:50:47,379 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5735b14d
09:50:47,379 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,379 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60b62f6
09:50:47,380 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d3579e0
09:50:47,381 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60b62f6
09:50:47,381 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1d96bf64
09:50:47,382 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5735b14d
09:50:47,383 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,383 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@b454096
09:50:47,383 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2bb34e41
09:50:47,384 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@439cf2df
09:50:47,385 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@b454096
09:50:47,385 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,385 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4fd00cff
09:50:47,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1d779845
09:50:47,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4fd00cff
09:50:47,387 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@73a43f81
09:50:47,387 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@490f163b
09:50:47,388 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12b7530
09:50:47,389 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
09:50:47,389 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68bd9607
09:50:47,389 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76c4db49
09:50:47,389 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@519f8603
09:50:47,389 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68bd9607
09:50:47,390 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,390 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@57a65cf1
09:50:47,390 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b1fff2b
09:50:47,391 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@57a65cf1
09:50:47,391 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@401758d2
09:50:47,392 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@263ee73b
09:50:47,392 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,392 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@41f4ec39
09:50:47,393 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@36c0b403
09:50:47,393 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@23852c7f
09:50:47,393 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c2c5810
09:50:47,393 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4349c220
09:50:47,394 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f67e563
09:50:47,394 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5e04d6b0
09:50:47,394 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@41f4ec39
09:50:47,394 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65e7deb6
09:50:47,395 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,395 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@11496fc2
09:50:47,396 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@11496fc2
09:50:47,396 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:47,396 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77e64656
09:50:47,397 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77e64656
09:50:47,397 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2f13f599
09:50:57,253 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
09:50:57,575 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=32442 minTime=32442 maxTime=32442
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=32368 minTime=1 maxTime=27
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=132 minTime=132 maxTime=132
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=188 minTime=188 maxTime=188

09:50:57,578 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:50:57,578 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:50:57,578 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:50:57,584 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:50:59,293 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:59,293 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@19dc2481
09:50:59,293 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@19dc2481
09:50:59,319 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:50:59,320 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1fc1cb1
09:50:59,320 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1fc1cb1
09:50:59,320 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ebfa97b
09:51:04,396 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=5431 minTime=5431 maxTime=5431
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=197 minTime=197 maxTime=197
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=1167 minTime=1167 maxTime=1167

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 39.731 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CFB_NoPadding_Test
09:51:04,415 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
09:51:04,417 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:04,417 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:04,418 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:04,423 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:04,430 WARN  [main][KeyStore] verifyMAC: MAC verification failed! macAlgorithm=HMACSHA1 expectedMAC=6a7b74fb6c72ae7f12648dd5380339dfc8482f8a calculatedMAC=b6af0e44b07cfd23ebe4418b15e5dc7afbe17edf
09:51:04,430 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:51:04,432 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:04,433 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:04,433 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:04,438 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:04,445 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:04,445 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8c30cbb
09:51:04,450 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:04,450 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d78cd7b
09:51:04,457 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:04,457 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45fa65c4
09:51:04,463 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:04,463 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a2f4ac0
09:51:04,470 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:04,470 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76d4a117
09:51:04,477 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:04,478 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:04,478 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:04,483 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:04,490 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:04,491 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:04,491 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:04,500 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:04,501 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:04,501 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:04,507 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:05,020 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@338652ff
09:51:05,022 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@37b2fdc6
09:51:05,023 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4832eebb
09:51:05,027 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:05,027 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4832eebb
09:51:05,028 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4bda5048
09:51:05,028 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4bda5048
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4e021bd
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16c701cb
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76d4a117
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a2f4ac0
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45fa65c4
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d78cd7b
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8c30cbb
09:51:05,029 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6346c21b
09:51:05,030 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:05,030 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16c701cb
09:51:05,030 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a71ad88
09:51:05,030 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:05,030 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a71ad88
09:51:19,273 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:19,996 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,051 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,101 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,127 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,158 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,171 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,200 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:20,236 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
09:51:24,430 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,463 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,513 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,551 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,572 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,581 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,591 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,607 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,633 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,680 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,682 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,710 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,771 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,779 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,790 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:24,800 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:36,297 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
09:51:36,693 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=31789 minTime=31789 maxTime=31789
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=31715 minTime=0 maxTime=65
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=16 minTime=16 maxTime=16
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=380 minTime=380 maxTime=380

09:51:36,696 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:36,696 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:36,697 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:36,702 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:51:38,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7491616e
09:51:42,916 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3aa19e64
09:51:42,917 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:42,917 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3aa19e64
09:51:42,917 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7f571924
09:51:42,918 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:42,919 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7f571924
09:51:42,919 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7188d649
09:51:42,919 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:42,919 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7188d649
09:51:42,919 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4eac8cda
09:51:42,919 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:42,919 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4eac8cda
09:51:48,466 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=9235 minTime=9235 maxTime=9235
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=108 minTime=108 maxTime=108
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2402 minTime=2402 maxTime=2402

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 44.063 sec
Running org.cumulus4j.keystore.test.KeyStoreCompatibilityTest
09:51:48,497 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,497 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:48,497 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:48,498 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:48,500 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,508 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,509 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,509 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,510 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,513 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,521 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,522 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,522 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,522 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,525 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,536 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,536 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,536 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,536 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,539 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,550 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,550 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,550 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,550 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,553 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,560 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,561 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,561 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,561 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,564 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,572 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,573 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,573 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,574 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,576 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,588 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,588 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,588 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,589 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,591 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,603 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,603 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,603 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,603 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,606 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,616 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,617 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:48,617 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:48,617 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:48,619 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,627 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,627 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,627 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,628 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,630 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,638 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,638 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,638 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,638 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,641 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,651 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,652 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,652 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,652 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,654 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,665 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,665 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,665 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,666 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,668 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,676 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,676 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,676 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,677 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,679 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,687 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,687 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,687 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,688 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:48,690 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,701 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,701 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,701 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:48,701 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,704 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,715 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
09:51:48,715 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:48,715 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:48,716 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:48,718 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
09:51:48,721 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:48,721 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:48,721 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:48,727 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:48,955 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,955 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@747fb9e1
09:51:48,955 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@747fb9e1
09:51:48,956 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,956 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1dc3e93d
09:51:48,956 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1dc3e93d
09:51:48,957 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,957 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@352eb2d3
09:51:48,957 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@352eb2d3
09:51:48,957 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,958 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@58daff8d
09:51:48,958 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@58daff8d
09:51:48,958 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,958 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3f9e9cbc
09:51:48,959 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3f9e9cbc
09:51:48,959 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,959 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@215a730d
09:51:48,959 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@215a730d
09:51:48,960 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,960 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65786232
09:51:48,960 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65786232
09:51:48,961 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,961 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@fb8a556
09:51:48,961 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@fb8a556
09:51:48,961 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@236eb92a
09:51:48,962 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,962 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a3e8692
09:51:48,962 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a3e8692
09:51:48,963 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,963 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@250f0f9f
09:51:48,963 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@250f0f9f
09:51:48,964 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,964 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5081a2e1
09:51:48,964 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5081a2e1
09:51:48,965 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,965 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b9e41fb
09:51:48,965 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b9e41fb
09:51:48,966 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,966 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10e8e1af
09:51:48,966 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10e8e1af
09:51:48,967 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,967 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48e18517
09:51:48,967 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48e18517
09:51:48,968 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,968 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f45abe3
09:51:48,968 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f45abe3
09:51:48,969 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,969 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ed2fe7
09:51:48,969 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ed2fe7
09:51:48,970 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,970 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74e42433
09:51:48,970 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74e42433
09:51:48,970 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:48,970 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2e04cc39
09:51:48,971 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2e04cc39
09:51:49,233 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:49,233 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
09:51:49,233 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:49,236 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:49,433 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:49,433 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
09:51:49,434 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:49,436 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:49,649 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:49,649 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:49,650 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
09:51:49,655 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:49,836 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:49,837 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:49,837 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:49,843 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:50,073 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:50,073 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
09:51:50,074 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:50,077 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:50,325 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
09:51:50,325 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
09:51:50,325 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:50,328 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:50,645 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:50,645 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:50,645 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
09:51:50,651 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:50,936 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
09:51:50,936 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:50,936 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:51:50,942 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
09:51:51,206 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,207 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@529b2735
09:51:51,207 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482b32f1
09:51:51,207 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@529b2735
09:51:51,208 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,208 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6929beb3
09:51:51,208 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@33a70fb5
09:51:51,208 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6929beb3
09:51:51,209 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,209 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@71ee2072
09:51:51,209 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@489456cd
09:51:51,209 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@71ee2072
09:51:51,209 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ced1f5
09:51:51,210 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,210 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5371b6f5
09:51:51,210 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ea2b99f
09:51:51,210 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5371b6f5
09:51:51,211 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,211 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a80b827
09:51:51,211 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8ba0dd4
09:51:51,212 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a80b827
09:51:51,212 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,213 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ea6e962
09:51:51,213 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@502438db
09:51:51,213 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ea6e962
09:51:51,214 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,214 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6897ccbc
09:51:51,214 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46028049
09:51:51,214 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6897ccbc
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.811 sec
Running org.cumulus4j.keystore.test.KeyStoreKeyTest
09:51:51,300 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
09:51:51,302 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:51,302 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:51,302 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:51,308 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:51,314 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
09:51:51,314 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:51:51,316 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:51,316 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:51,316 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:51,322 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:51,328 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,328 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@430eaae5
09:51:51,334 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,334 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77f5c2c7
09:51:51,340 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,340 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@27c6d458
09:51:51,346 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,346 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e9ef1ce
09:51:51,356 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:51:51,356 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@515441f
09:51:51,365 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:51,365 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:51,365 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:51,371 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:51,377 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:51,378 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:51,378 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:51,388 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:51:51,388 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:51:51,388 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:51:51,394 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:51:57,386 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:51:57,579 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:00,764 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@27ad0ff8
09:52:00,764 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@52f37721
09:52:00,764 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:00,764 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@52f37721
09:52:00,765 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e124212
09:52:00,765 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:00,766 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e124212
09:52:00,766 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2baec86a
09:52:00,766 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@674da52b
09:52:00,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@515441f
09:52:00,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e9ef1ce
09:52:00,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@27c6d458
09:52:00,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77f5c2c7
09:52:00,768 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@430eaae5
09:52:00,768 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a96df09
09:52:00,768 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:00,770 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@674da52b
09:52:00,771 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b955dd5
09:52:03,229 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,415 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,418 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,432 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,477 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,485 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,493 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:04,500 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:17,631 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
09:52:18,307 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=26237 minTime=26237 maxTime=26237
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=26231 minTime=0 maxTime=39
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=14 minTime=14 maxTime=14
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=661 minTime=661 maxTime=661

09:52:18,309 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:18,309 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:52:18,309 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:52:18,315 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:52:19,176 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7609e871
09:52:20,235 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:33,944 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ad1c003
09:52:33,944 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,944 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ad1c003
09:52:33,944 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@30951aeb
09:52:33,944 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,944 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@30951aeb
09:52:33,944 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@d1a5338
09:52:33,945 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,945 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@d1a5338
09:52:33,945 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f2c402a
09:52:33,945 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,946 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f2c402a
09:52:33,946 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ed2bd5d
09:52:33,946 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,946 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ed2bd5d
09:52:33,947 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d9d2995
09:52:33,947 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,947 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d9d2995
09:52:33,947 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3708ab98
09:52:33,949 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:33,949 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3708ab98
09:52:36,313 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:36,696 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:37,581 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=14347 minTime=14347 maxTime=14347
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=99 minTime=99 maxTime=99
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=4802 minTime=4802 maxTime=4802

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.292 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CBC_PKCS5Padding_Test
09:52:37,596 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
09:52:37,598 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:37,598 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:52:37,599 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:52:37,604 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:52:37,610 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
09:52:37,610 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
09:52:37,612 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:37,612 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:52:37,612 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:52:37,618 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:52:37,624 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,624 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b2d0c2e
09:52:37,630 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,630 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7a7ac690
09:52:37,636 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,636 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@128256c2
09:52:37,642 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,642 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2b0de40c
09:52:37,648 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,648 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1cf6e5e9
09:52:37,655 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:37,655 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:52:37,656 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:52:37,661 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:52:37,667 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:52:37,668 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:52:37,668 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:37,676 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:52:37,676 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:52:37,676 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:52:37,682 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:52:37,709 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@791a9134
09:52:37,710 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28c1b980
09:52:37,710 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,710 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@58c30f56
09:52:37,710 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e2404a0
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b2d0c2e
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7a7ac690
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@128256c2
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2b0de40c
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1cf6e5e9
09:52:37,711 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@58c30f56
09:52:37,712 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45317223
09:52:37,712 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,712 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79c45dbe
09:52:37,713 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79c45dbe
09:52:37,713 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:52:37,713 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6bf1a3f4
09:52:37,713 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6bf1a3f4
09:52:46,063 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,491 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,505 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,518 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,530 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,545 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,557 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,568 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,582 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,596 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,610 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,624 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,635 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,646 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,659 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,673 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,684 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,695 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,709 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:48,721 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:49,233 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:49,433 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:49,649 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:49,837 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:50,073 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:50,324 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:50,646 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:50,936 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,300 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,302 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,316 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,365 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,372 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,379 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:52:51,387 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:53:10,544 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
09:53:10,967 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=32862 minTime=32862 maxTime=32862
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=32850 minTime=0 maxTime=32
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=16 minTime=16 maxTime=16
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=407 minTime=407 maxTime=407

09:53:10,969 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
09:53:10,969 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
09:53:10,969 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
09:53:10,975 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
09:53:12,164 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e5a8246
09:53:12,164 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c08251
09:53:12,165 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
09:53:12,165 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c08251
09:53:17,645 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:53:18,310 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
09:53:24,398 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=10424 minTime=10424 maxTime=10424
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=111 minTime=111 maxTime=111
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2871 minTime=2871 maxTime=2871

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.814 sec
Running org.cumulus4j.keystore.test.LongPropertyTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 sec

Results :

Tests run: 42, Failures: 0, Errors: 0, Skipped: 0

[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-bundle-plugin:2.3.4:bundle (default-bundle) @ org.cumulus4j.keystore ---
[INFO] 
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ org.cumulus4j.keystore ---
[INFO] org already added, skipping
[INFO] org/cumulus4j already added, skipping
[INFO] org/cumulus4j/keystore already added, skipping
[INFO] org/cumulus4j/keystore/prop already added, skipping
[INFO] LICENCE.txt already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/org.cumulus4j.keystore-1.2.1-SNAPSHOT-sources.jar
[INFO] org already added, skipping
[INFO] org/cumulus4j already added, skipping
[INFO] org/cumulus4j/keystore already added, skipping
[INFO] org/cumulus4j/keystore/prop already added, skipping
[INFO] LICENCE.txt already added, skipping
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ org.cumulus4j.keystore ---
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/org.cumulus4j.keystore-1.2.1-SNAPSHOT.jar to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.keystore/1.2.1-SNAPSHOT/org.cumulus4j.keystore-1.2.1-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/pom.xml to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.keystore/1.2.1-SNAPSHOT/org.cumulus4j.keystore-1.2.1-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/org.cumulus4j.keystore-1.2.1-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.keystore/1.2.1-SNAPSHOT/org.cumulus4j.keystore-1.2.1-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-bundle-plugin:2.3.4:install (default-install) @ org.cumulus4j.keystore ---
[WARNING] Ignoring project type bundle - supportedProjectTypes = []