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.KeyStoreKey_AES_CBC_PKCS5Padding_Test
05:40:28,691 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
05:40:28,702 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:28,703 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:40:28,705 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:28,806 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
05:40:28,806 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
05:40:28,807 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
05:40:28,808 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
05:40:28,809 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
05:40:28,809 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
05:40:28,810 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
05:40:28,811 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
05:40:28,811 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
05:40:28,811 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
05:40:28,813 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3WITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
05:40:28,813 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MACWITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
05:40:28,814 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
05:40:28,814 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
05:40:28,815 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
05:40:28,815 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
05:40:28,816 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
05:40:28,816 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
05:40:28,817 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA384"
05:40:28,818 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA512"
05:40:28,819 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
05:40:28,819 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
05:40:28,820 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
05:40:28,820 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
05:40:28,820 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
05:40:28,821 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
05:40:28,821 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
05:40:28,822 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
05:40:28,822 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
05:40:28,823 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
05:40:28,823 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
05:40:28,823 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
05:40:28,824 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
05:40:28,824 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
05:40:28,825 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
05:40:28,826 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
05:40:28,826 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
05:40:28,826 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
05:40:28,827 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
05:40:28,827 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
05:40:28,827 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
05:40:28,828 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
05:40:28,828 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
05:40:28,828 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
05:40:28,829 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
05:40:28,829 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
05:40:28,829 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
05:40:28,830 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
05:40:28,830 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
05:40:28,830 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
05:40:28,832 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACTIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
05:40:28,832 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
05:40:28,832 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
05:40:28,843 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="ElGamal" engineClass="org.bouncycastle.crypto.engines.ElGamalEngine"
05:40:28,846 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="NaccacheStern" engineClass="org.bouncycastle.crypto.engines.NaccacheSternEngine"
05:40:28,849 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="RSA" engineClass="org.bouncycastle.crypto.engines.RSABlindedEngine"
05:40:28,854 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES" engineClass="org.bouncycastle.crypto.engines.AESEngine"
05:40:28,863 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.fast" engineClass="org.bouncycastle.crypto.engines.AESFastEngine"
05:40:28,866 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.light" engineClass="org.bouncycastle.crypto.engines.AESLightEngine"
05:40:28,869 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Blowfish" engineClass="org.bouncycastle.crypto.engines.BlowfishEngine"
05:40:28,874 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia" engineClass="org.bouncycastle.crypto.engines.CamelliaEngine"
05:40:28,877 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia.light" engineClass="org.bouncycastle.crypto.engines.CamelliaLightEngine"
05:40:28,884 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST5" engineClass="org.bouncycastle.crypto.engines.CAST5Engine"
05:40:28,885 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST6" engineClass="org.bouncycastle.crypto.engines.CAST6Engine"
05:40:28,889 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DESede" engineClass="org.bouncycastle.crypto.engines.DESedeEngine"
05:40:28,889 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DES" engineClass="org.bouncycastle.crypto.engines.DESEngine"
05:40:28,894 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GOST28147" engineClass="org.bouncycastle.crypto.engines.GOST28147Engine"
05:40:28,896 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Noekeon" engineClass="org.bouncycastle.crypto.engines.NoekeonEngine"
05:40:28,897 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Null" engineClass="org.bouncycastle.crypto.engines.NullEngine"
05:40:28,900 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC2" engineClass="org.bouncycastle.crypto.engines.RC2Engine"
05:40:28,903 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-32" engineClass="org.bouncycastle.crypto.engines.RC532Engine"
05:40:28,905 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-64" engineClass="org.bouncycastle.crypto.engines.RC564Engine"
05:40:28,907 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC6" engineClass="org.bouncycastle.crypto.engines.RC6Engine"
05:40:28,910 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Rijndael" engineClass="org.bouncycastle.crypto.engines.RijndaelEngine"
05:40:28,913 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SEED" engineClass="org.bouncycastle.crypto.engines.SEEDEngine"
05:40:28,918 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Serpent" engineClass="org.bouncycastle.crypto.engines.SerpentEngine"
05:40:28,920 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SKIPJACK" engineClass="org.bouncycastle.crypto.engines.SkipjackEngine"
05:40:28,921 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="TEA" engineClass="org.bouncycastle.crypto.engines.TEAEngine"
05:40:28,926 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Twofish" engineClass="org.bouncycastle.crypto.engines.TwofishEngine"
05:40:28,927 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="XTEA" engineClass="org.bouncycastle.crypto.engines.XTEAEngine"
05:40:28,930 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Grain-128" engineClass="org.bouncycastle.crypto.engines.Grain128Engine"
05:40:28,932 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GRAIN-V1" engineClass="org.bouncycastle.crypto.engines.Grainv1Engine"
05:40:28,934 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-128" engineClass="org.bouncycastle.crypto.engines.HC128Engine"
05:40:28,935 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-256" engineClass="org.bouncycastle.crypto.engines.HC256Engine"
05:40:28,937 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="ISAAC" engineClass="org.bouncycastle.crypto.engines.ISAACEngine"
05:40:28,938 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC4" engineClass="org.bouncycastle.crypto.engines.RC4Engine"
05:40:28,942 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Salsa20" engineClass="org.bouncycastle.crypto.engines.Salsa20Engine"
05:40:28,943 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CBC" modeClass="org.bouncycastle.crypto.modes.CBCBlockCipher"
05:40:28,945 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="CCM" modeClass="org.bouncycastle.crypto.modes.CCMBlockCipher"
05:40:28,947 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,948 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,949 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,950 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,951 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,952 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
05:40:28,954 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CTS" modeClass="org.bouncycastle.crypto.modes.CTSBlockCipher"
05:40:28,955 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CBC-CTS" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCBCCTSBlockCipher"
05:40:28,956 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="EAX" modeClass="org.bouncycastle.crypto.modes.EAXBlockCipher"
05:40:28,957 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="GCM" modeClass="org.bouncycastle.crypto.modes.GCMBlockCipher"
05:40:28,958 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="GOFB" modeClass="org.bouncycastle.crypto.modes.GOFBBlockCipher"
05:40:28,959 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,960 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,961 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,962 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,963 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,964 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,964 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,964 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,964 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,965 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,965 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
05:40:28,966 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="SIC" modeClass="org.bouncycastle.crypto.modes.SICBlockCipher"
05:40:28,984 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126-2" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
05:40:28,985 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
05:40:28,985 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO7816-4" paddingClass="org.bouncycastle.crypto.paddings.ISO7816d4Padding"
05:40:28,987 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS7" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
05:40:28,987 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS5" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
05:40:28,988 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="TBC" paddingClass="org.bouncycastle.crypto.paddings.TBCPadding"
05:40:28,990 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="X9.23" paddingClass="org.bouncycastle.crypto.paddings.X923Padding"
05:40:28,991 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ZeroByte" paddingClass="org.bouncycastle.crypto.paddings.ZeroBytePadding"
05:40:28,993 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="ISO9796-1" paddingClass="org.bouncycastle.crypto.encodings.ISO9796d1Encoding"
05:40:28,994 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEP" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
05:40:28,994 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEPWITHSHA1ANDMGF1" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
05:40:28,995 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="PKCS1" paddingClass="org.bouncycastle.crypto.encodings.PKCS1Encoding"
05:40:29,007 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DH" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DHBasicKeyPairGeneratorFactory"
05:40:29,008 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DSAKeyPairGeneratorFactory"
05:40:29,009 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="ElGamal" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.ElGamalKeyPairGeneratorFactory"
05:40:29,010 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="GOST3410" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.GOST3410KeyPairGeneratorFactory"
05:40:29,011 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="NaccacheStern" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.NaccacheSternKeyPairGeneratorFactory"
05:40:29,013 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="RSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.RSAKeyPairGeneratorFactory"
05:40:29,020 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:29,040 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
05:40:29,040 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
05:40:29,042 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:29,042 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:40:29,043 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:29,051 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:29,063 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:29,063 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28feb3fa
05:40:29,072 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:29,072 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@675d3402
05:40:29,084 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:29,084 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51565ec2
05:40:29,092 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:29,092 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482f8f11
05:40:29,103 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:29,103 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1593948d
05:40:29,116 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:29,116 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:40:29,116 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:29,124 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:29,135 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:29,136 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:29,137 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:29,152 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:29,152 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:40:29,152 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:29,159 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:31,729 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28feb3fa
05:40:31,733 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@635cb856
05:40:31,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6d9b5a4d
05:40:31,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@69a4d326
05:40:31,737 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@69a4d326
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ca65076
05:40:31,737 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ca65076
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2a3ec3bc
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1dc0bd84
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1593948d
05:40:31,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482f8f11
05:40:31,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51565ec2
05:40:31,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@675d3402
05:40:31,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@52b270f9
05:40:31,738 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:31,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1dc0bd84
05:40:56,735 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
05:40:56,881 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4c89af39
05:40:56,882 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:56,882 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4c89af39
05:40:56,977 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=27573 minTime=27573 maxTime=27573
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=27323 minTime=0 maxTime=29
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=35 minTime=35 maxTime=35
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=205 minTime=205 maxTime=205

05:40:56,979 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:40:56,979 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:40:56,980 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:40:56,988 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:40:57,400 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1076b637
05:40:57,400 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8c20520
05:40:57,400 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:40:57,401 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8c20520
05:41:03,150 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=5016 minTime=5016 maxTime=5016
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=129 minTime=129 maxTime=129
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=998 minTime=998 maxTime=998

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 34.957 sec
Running org.cumulus4j.keystore.test.DateDependentKeyStrategyTest
05:41:03,170 DEBUG [main][DateDependentKeyStrategy] init: Calculated numberOfKeysToGenerate=5761
05:41:03,170 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:03,170 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:03,170 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:03,184 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:03,942 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@49b69979
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.897 sec
Running org.cumulus4j.keystore.test.KeyStorePropertyTest
05:41:06,073 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,074 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,074 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,081 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,089 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,090 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,090 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,096 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,104 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,105 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,105 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,111 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,128 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,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.
05:41:06,128 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,134 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,149 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,149 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,149 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,155 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: 0.104 sec
Running org.cumulus4j.keystore.test.KeyStoreKeyTest
05:41:06,180 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
05:41:06,181 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,181 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,182 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,187 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,194 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
05:41:06,195 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
05:41:06,196 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,196 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,197 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,203 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,209 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:06,209 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26653222
05:41:06,215 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:06,215 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3532ec19
05:41:06,223 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:06,223 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68c4039c
05:41:06,229 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:06,229 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ae45eb6
05:41:06,235 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:06,235 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59f99ea
05:41:06,243 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,243 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,243 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,249 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,256 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:06,256 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,257 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,264 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:06,264 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:06,264 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:06,270 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:08,713 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48c71711
05:41:08,716 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,716 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6da5d730
05:41:08,716 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@75d624a1
05:41:08,716 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6da5d730
05:41:08,717 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,717 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8d60633
05:41:08,717 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@78d9e7a7
05:41:08,717 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8d60633
05:41:08,718 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,718 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b06a3db
05:41:08,718 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7f316b36
05:41:08,718 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b06a3db
05:41:08,719 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,720 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@476e1d7d
05:41:08,720 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@476e1d7d
05:41:08,721 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,721 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@703d3ee8
05:41:08,721 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7eab534f
05:41:08,721 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@703d3ee8
05:41:08,723 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a37ff77
05:41:08,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a37ff77
05:41:08,724 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,724 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d058eb9
05:41:08,724 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@24562d8f
05:41:08,725 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d058eb9
05:41:08,727 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c33361
05:41:08,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c33361
05:41:08,729 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@35baa849
05:41:08,730 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@245b9801
05:41:08,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26653222
05:41:08,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3532ec19
05:41:08,731 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68c4039c
05:41:08,731 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ae45eb6
05:41:08,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59f99ea
05:41:08,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@245b9801
05:41:08,733 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@31f4e350
05:41:08,734 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28358634
05:41:08,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28358634
05:41:08,736 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:08,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482d8b9b
05:41:08,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482d8b9b
05:41:08,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@30ce890f
05:41:28,686 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:28,703 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,043 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,117 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,129 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,139 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,151 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:29,653 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
05:41:30,092 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=23383 minTime=23383 maxTime=23383
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=23245 minTime=0 maxTime=24
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=14 minTime=14 maxTime=14
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=424 minTime=424 maxTime=424

05:41:30,094 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:30,094 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:30,094 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:30,100 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:31,257 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26b60225
05:41:42,131 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=9231 minTime=9231 maxTime=9231
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=85 minTime=85 maxTime=85
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2698 minTime=2698 maxTime=2698

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 35.96 sec
Running org.cumulus4j.keystore.test.KeyStoreCompatibilityTest
05:41:42,139 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:42,139 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:42,139 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:42,146 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:42,460 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:42,460 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
05:41:42,460 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:42,464 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:42,625 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:42,625 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
05:41:42,625 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:42,628 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:42,811 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:42,811 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:42,811 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:42,817 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:42,982 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:42,982 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:42,982 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:42,989 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:43,193 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:43,194 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
05:41:43,194 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:43,198 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:43,443 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:43,443 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
05:41:43,443 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:43,447 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:43,735 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:43,735 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:43,735 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:43,741 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,012 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,012 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:44,013 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,019 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,345 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,345 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:44,345 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:44,346 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,348 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,357 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,357 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,357 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,357 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,360 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,367 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,368 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,368 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,368 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,370 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,381 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,381 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,381 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,381 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,384 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,396 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,396 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,396 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,396 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,398 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,406 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,406 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,406 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,406 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,408 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,417 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,417 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,417 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,417 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,420 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,430 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,430 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,430 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,430 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,433 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,445 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,445 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,445 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,445 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,448 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,458 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,458 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:41:44,458 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:41:44,459 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,461 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,469 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,469 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,469 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,469 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,471 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,479 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,479 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,479 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,479 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,481 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,493 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,493 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,493 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,493 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,496 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,507 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,507 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,507 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,507 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,510 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,517 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,518 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,518 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,518 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,521 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,528 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,529 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,529 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,529 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
05:41:44,531 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,560 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,560 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,560 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:41:44,560 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,563 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
05:41:44,574 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
05:41:44,574 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
05:41:44,574 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,574 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
05:41:44,577 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.442 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CBC_PKCS5Padding_Test
05:41:44,584 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
05:41:44,586 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,586 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:44,586 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,593 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:41:44,599 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
05:41:44,599 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
05:41:44,600 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,600 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:44,600 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,607 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:41:44,613 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:44,613 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5622fdf
05:41:44,619 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:44,619 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4883b407
05:41:44,626 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:44,626 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d9d1a19
05:41:44,632 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:44,632 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c0f4a
05:41:44,639 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:44,639 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1794d431
05:41:44,648 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,648 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:44,648 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,654 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:41:44,661 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:41:44,661 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,661 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,669 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:41:44,669 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:41:44,669 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:41:44,675 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:41:53,747 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,747 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@22b634e1
05:41:53,748 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,749 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e3027f
05:41:53,749 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4221655d
05:41:53,749 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e3027f
05:41:53,750 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,750 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5490f016
05:41:53,750 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4792c759
05:41:53,750 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5490f016
05:41:53,751 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59fcd52e
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@103e58c1
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59fcd52e
05:41:53,751 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@437d1a90
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1284a5a9
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@437d1a90
05:41:53,751 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2d6e4772
05:41:53,751 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ff4ea4e
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2d6e4772
05:41:53,752 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6476d0ba
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53689870
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6476d0ba
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c6200b8
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@22b634e1
05:41:53,752 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@642c2de0
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@360dd2df
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@642c2de0
05:41:53,752 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21317776
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3dcad428
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21317776
05:41:53,752 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@388cad5c
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@388cad5c
05:41:53,752 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60cf0bb8
05:41:53,752 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60cf0bb8
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4aef4a10
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4aef4a10
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@216facf1
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@216facf1
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c5403cd
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c5403cd
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@64de4534
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@64de4534
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@54d12c75
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@54d12c75
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8850a3a
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8850a3a
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@54932a8c
05:41:53,753 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@54932a8c
05:41:53,753 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@427232cf
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@427232cf
05:41:53,754 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f1d0c70
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f1d0c70
05:41:53,754 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3fe91173
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3fe91173
05:41:53,754 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@72e38b48
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@72e38b48
05:41:53,754 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21d42b91
05:41:53,754 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21d42b91
05:41:53,754 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56ddcd57
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56ddcd57
05:41:53,755 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21483c76
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@21483c76
05:41:53,755 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f9f4adc
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f9f4adc
05:41:53,755 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f17681d
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f17681d
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7798a3dd
05:41:53,755 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,755 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@17538ad4
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@222feb48
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5622fdf
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4883b407
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d9d1a19
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c0f4a
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1794d431
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@17538ad4
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@568c2890
05:41:53,756 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@64b8efd7
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@64b8efd7
05:41:53,756 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f36dcbe
05:41:53,756 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f36dcbe
05:41:53,757 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@329e0fbb
05:41:56,772 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:41:56,980 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:02,157 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:03,168 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:06,074 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,090 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,104 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,118 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,128 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,141 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,150 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,163 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,179 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,181 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,197 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,243 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,251 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,257 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:06,265 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:13,502 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
05:42:13,802 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=28826 minTime=28826 maxTime=28826
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=28577 minTime=0 maxTime=39
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=13 minTime=13 maxTime=13
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=287 minTime=287 maxTime=287

05:42:13,803 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:13,804 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:13,804 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
05:42:13,809 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:16,139 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:16,140 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56179d0
05:42:16,142 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2684f8fc
05:42:16,142 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56179d0
05:42:23,987 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=7906 minTime=7906 maxTime=7906
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=111 minTime=111 maxTime=111
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2142 minTime=2142 maxTime=2142

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 39.41 sec
Running org.cumulus4j.keystore.test.LongPropertyTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.017 sec
Running org.cumulus4j.keystore.test.KeyStoreUserTest
05:42:24,019 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,019 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,019 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,025 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,026 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,026 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,026 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,032 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,045 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,045 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,045 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,051 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,052 WARN  [main][KeyStore] getMasterKey: Unknown userName: null
05:42:24,053 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,053 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,053 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,060 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,066 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
05:42:24,066 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
05:42:24,068 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,068 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,068 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,074 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,092 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,092 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@343f4d3d
05:42:24,093 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,094 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,094 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,100 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,118 DEBUG [main][CachedMasterKey] clear: Clearing for userName='bieber'.
05:42:24,118 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53b32d7
05:42:24,119 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,119 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,119 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,125 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,143 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,144 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5442a311
05:42:24,145 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,145 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,145 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,150 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,189 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "bieber"!
05:42:24,189 WARN  [main][KeyStore] getMasterKey: Wrong password for user "bieber"! MAC verification failed.
05:42:24,190 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,190 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,190 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
05:42:24,196 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
05:42:24,222 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
05:42:24,222 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.211 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CFB_NoPadding_Test
05:42:24,229 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
05:42:24,230 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,230 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,230 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:24,237 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:24,245 WARN  [main][KeyStore] verifyMAC: MAC verification failed! macAlgorithm=HMACSHA1 expectedMAC=2f7a280dcadb0a2f6ffe3aca48a7874462672b0b calculatedMAC=d0b60c10cd3e02eb9fb0a8817d5de38cda1596ae
05:42:24,245 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
05:42:24,246 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,246 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,247 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:24,253 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:24,260 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,260 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@548e7350
05:42:24,266 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,267 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a968a59
05:42:24,273 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,273 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4667ae56
05:42:24,280 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,280 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77cd7a0
05:42:24,286 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:24,286 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@204f30ec
05:42:24,294 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,294 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,294 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:24,300 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:24,306 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:24,306 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:24,307 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,315 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:24,315 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:24,315 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:24,321 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:29,668 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:30,094 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:38,725 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4667ae56
05:42:38,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7f8e13be
05:42:38,727 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d2f2823
05:42:38,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@330e79af
05:42:38,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d2f2823
05:42:38,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@32cc342a
05:42:38,729 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3dd0c22e
05:42:38,730 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
05:42:38,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79fa9b87
05:42:38,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39f95616
05:42:38,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@343f4d3d
05:42:38,731 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79fa9b87
05:42:38,732 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ac74867
05:42:38,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51bd26a3
05:42:38,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ac74867
05:42:38,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53b32d7
05:42:38,734 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
05:42:38,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c36a9b0
05:42:38,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@f0c19c6
05:42:38,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5442a311
05:42:38,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c36a9b0
05:42:38,735 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f58fc30
05:42:38,736 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@67684146
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2e5a0f32
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@67684146
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4177f116
05:42:38,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f58fc30
05:42:38,738 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59563c74
05:42:38,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c913469
05:42:38,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2cc8756
05:42:38,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59563c74
05:42:38,740 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7ccd3958
05:42:38,741 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,741 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@14100f29
05:42:38,741 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@61d753be
05:42:38,741 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@548e7350
05:42:38,741 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a968a59
05:42:38,741 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77cd7a0
05:42:38,742 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@204f30ec
05:42:38,742 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@14100f29
05:42:38,743 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7479ee5
05:42:38,744 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,744 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12d560ac
05:42:38,744 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12d560ac
05:42:38,745 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:38,745 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a85c98
05:42:38,746 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a85c98
05:42:38,746 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5d39a550
05:42:39,434 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:42,139 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:42,461 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:42,625 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:42,811 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:42,983 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:43,193 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:43,443 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:43,735 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,012 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,338 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,355 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,364 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,375 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,389 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,404 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,414 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,425 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,438 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,453 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,465 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,476 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,486 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,502 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,514 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,525 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,552 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,568 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,585 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,586 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,600 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,647 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,656 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,662 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
05:42:44,671 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:42:52,086 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
05:42:52,352 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=27766 minTime=27766 maxTime=27766
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=27488 minTime=0 maxTime=54
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=14 minTime=14 maxTime=14
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=250 minTime=250 maxTime=250

05:42:52,353 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
05:42:52,353 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
05:42:52,353 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
05:42:52,358 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
05:42:54,385 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@66b2a8a4
05:42:54,385 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f5b74de
05:42:54,386 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
05:42:54,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5f5b74de
05:42:56,980 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
05:43:00,857 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=6634 minTime=6634 maxTime=6634
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=86 minTime=86 maxTime=86
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=1758 minTime=1758 maxTime=1758

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.635 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 = []