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
10:55:05,777 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
10:55:05,791 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:05,792 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:05,794 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:05,914 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
10:55:05,915 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
10:55:05,916 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
10:55:05,916 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
10:55:05,918 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
10:55:05,918 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
10:55:05,919 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
10:55:05,919 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
10:55:05,920 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
10:55:05,920 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
10:55:05,922 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3WITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
10:55:05,922 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MACWITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
10:55:05,923 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
10:55:05,923 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
10:55:05,924 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
10:55:05,925 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
10:55:05,925 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
10:55:05,926 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
10:55:05,927 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA384"
10:55:05,928 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA512"
10:55:05,929 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
10:55:05,930 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
10:55:05,930 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
10:55:05,931 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
10:55:05,931 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
10:55:05,931 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
10:55:05,932 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
10:55:05,932 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
10:55:05,932 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
10:55:05,933 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
10:55:05,934 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
10:55:05,934 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
10:55:05,935 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
10:55:05,935 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
10:55:05,935 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
10:55:05,936 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
10:55:05,936 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
10:55:05,937 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
10:55:05,938 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
10:55:05,938 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
10:55:05,938 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
10:55:05,939 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
10:55:05,939 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
10:55:05,940 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
10:55:05,941 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
10:55:05,941 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
10:55:05,941 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
10:55:05,942 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
10:55:05,943 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
10:55:05,943 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
10:55:05,944 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACTIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
10:55:05,944 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
10:55:05,945 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
10:55:05,958 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="ElGamal" engineClass="org.bouncycastle.crypto.engines.ElGamalEngine"
10:55:05,961 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="NaccacheStern" engineClass="org.bouncycastle.crypto.engines.NaccacheSternEngine"
10:55:05,964 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="RSA" engineClass="org.bouncycastle.crypto.engines.RSABlindedEngine"
10:55:05,970 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES" engineClass="org.bouncycastle.crypto.engines.AESEngine"
10:55:05,977 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.fast" engineClass="org.bouncycastle.crypto.engines.AESFastEngine"
10:55:05,981 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.light" engineClass="org.bouncycastle.crypto.engines.AESLightEngine"
10:55:05,985 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Blowfish" engineClass="org.bouncycastle.crypto.engines.BlowfishEngine"
10:55:05,989 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia" engineClass="org.bouncycastle.crypto.engines.CamelliaEngine"
10:55:05,993 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia.light" engineClass="org.bouncycastle.crypto.engines.CamelliaLightEngine"
10:55:06,000 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST5" engineClass="org.bouncycastle.crypto.engines.CAST5Engine"
10:55:06,002 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST6" engineClass="org.bouncycastle.crypto.engines.CAST6Engine"
10:55:06,006 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DESede" engineClass="org.bouncycastle.crypto.engines.DESedeEngine"
10:55:06,006 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DES" engineClass="org.bouncycastle.crypto.engines.DESEngine"
10:55:06,011 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GOST28147" engineClass="org.bouncycastle.crypto.engines.GOST28147Engine"
10:55:06,013 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Noekeon" engineClass="org.bouncycastle.crypto.engines.NoekeonEngine"
10:55:06,014 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Null" engineClass="org.bouncycastle.crypto.engines.NullEngine"
10:55:06,018 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC2" engineClass="org.bouncycastle.crypto.engines.RC2Engine"
10:55:06,022 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-32" engineClass="org.bouncycastle.crypto.engines.RC532Engine"
10:55:06,023 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-64" engineClass="org.bouncycastle.crypto.engines.RC564Engine"
10:55:06,025 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC6" engineClass="org.bouncycastle.crypto.engines.RC6Engine"
10:55:06,029 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Rijndael" engineClass="org.bouncycastle.crypto.engines.RijndaelEngine"
10:55:06,033 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SEED" engineClass="org.bouncycastle.crypto.engines.SEEDEngine"
10:55:06,037 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Serpent" engineClass="org.bouncycastle.crypto.engines.SerpentEngine"
10:55:06,039 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SKIPJACK" engineClass="org.bouncycastle.crypto.engines.SkipjackEngine"
10:55:06,041 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="TEA" engineClass="org.bouncycastle.crypto.engines.TEAEngine"
10:55:06,046 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Twofish" engineClass="org.bouncycastle.crypto.engines.TwofishEngine"
10:55:06,047 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="XTEA" engineClass="org.bouncycastle.crypto.engines.XTEAEngine"
10:55:06,050 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Grain-128" engineClass="org.bouncycastle.crypto.engines.Grain128Engine"
10:55:06,052 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GRAIN-V1" engineClass="org.bouncycastle.crypto.engines.Grainv1Engine"
10:55:06,054 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-128" engineClass="org.bouncycastle.crypto.engines.HC128Engine"
10:55:06,056 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-256" engineClass="org.bouncycastle.crypto.engines.HC256Engine"
10:55:06,057 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="ISAAC" engineClass="org.bouncycastle.crypto.engines.ISAACEngine"
10:55:06,059 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC4" engineClass="org.bouncycastle.crypto.engines.RC4Engine"
10:55:06,063 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Salsa20" engineClass="org.bouncycastle.crypto.engines.Salsa20Engine"
10:55:06,064 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CBC" modeClass="org.bouncycastle.crypto.modes.CBCBlockCipher"
10:55:06,066 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="CCM" modeClass="org.bouncycastle.crypto.modes.CCMBlockCipher"
10:55:06,068 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,069 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,070 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,071 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,071 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,071 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,071 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,071 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,072 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,073 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,074 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
10:55:06,075 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CTS" modeClass="org.bouncycastle.crypto.modes.CTSBlockCipher"
10:55:06,076 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CBC-CTS" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCBCCTSBlockCipher"
10:55:06,077 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="EAX" modeClass="org.bouncycastle.crypto.modes.EAXBlockCipher"
10:55:06,078 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="GCM" modeClass="org.bouncycastle.crypto.modes.GCMBlockCipher"
10:55:06,079 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="GOFB" modeClass="org.bouncycastle.crypto.modes.GOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,081 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,082 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,083 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,083 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,083 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,083 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,084 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,085 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,086 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,086 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
10:55:06,087 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="SIC" modeClass="org.bouncycastle.crypto.modes.SICBlockCipher"
10:55:06,107 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126-2" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
10:55:06,108 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
10:55:06,109 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO7816-4" paddingClass="org.bouncycastle.crypto.paddings.ISO7816d4Padding"
10:55:06,110 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS7" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
10:55:06,110 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS5" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
10:55:06,111 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="TBC" paddingClass="org.bouncycastle.crypto.paddings.TBCPadding"
10:55:06,112 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="X9.23" paddingClass="org.bouncycastle.crypto.paddings.X923Padding"
10:55:06,114 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ZeroByte" paddingClass="org.bouncycastle.crypto.paddings.ZeroBytePadding"
10:55:06,115 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="ISO9796-1" paddingClass="org.bouncycastle.crypto.encodings.ISO9796d1Encoding"
10:55:06,116 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEP" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
10:55:06,116 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEPWITHSHA1ANDMGF1" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
10:55:06,118 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="PKCS1" paddingClass="org.bouncycastle.crypto.encodings.PKCS1Encoding"
10:55:06,129 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DH" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DHBasicKeyPairGeneratorFactory"
10:55:06,130 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DSAKeyPairGeneratorFactory"
10:55:06,131 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="ElGamal" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.ElGamalKeyPairGeneratorFactory"
10:55:06,132 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="GOST3410" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.GOST3410KeyPairGeneratorFactory"
10:55:06,132 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="NaccacheStern" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.NaccacheSternKeyPairGeneratorFactory"
10:55:06,135 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="RSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.RSAKeyPairGeneratorFactory"
10:55:06,141 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:06,166 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
10:55:06,166 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:55:06,169 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:06,169 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:06,169 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:06,180 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:06,194 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:06,195 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28feb3fa
10:55:06,205 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:06,205 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@675d3402
10:55:06,221 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:06,221 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51565ec2
10:55:06,230 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:06,230 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482f8f11
10:55:06,243 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:06,244 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1593948d
10:55:06,259 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:06,259 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:06,259 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:06,269 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:06,281 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:06,282 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:06,283 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:06,303 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:06,303 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:06,303 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:06,312 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:09,014 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60c94dfd
10:55:09,015 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e0eaf94
10:55:09,015 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a31268d
10:55:09,016 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:09,016 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a31268d
10:55:09,016 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2f33a5af
10:55:09,017 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:09,017 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2f33a5af
10:55:09,017 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53fa311
10:55:09,017 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1e56ce2d
10:55:09,018 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1593948d
10:55:09,018 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@482f8f11
10:55:09,018 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51565ec2
10:55:09,018 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@675d3402
10:55:09,018 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28feb3fa
10:55:09,019 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7db09624
10:55:09,019 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:09,019 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1e56ce2d
10:55:34,377 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
10:55:34,520 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f1c5ae0
10:55:34,520 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:34,520 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f1c5ae0
10:55:34,602 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=28062 minTime=28062 maxTime=28062
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=27817 minTime=0 maxTime=34
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=39 minTime=39 maxTime=39
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=185 minTime=185 maxTime=185

10:55:34,604 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:34,605 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:34,605 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:55:34,614 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:55:35,125 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:35,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@37434f39
10:55:35,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f4cd85a
10:55:35,127 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@37434f39
10:55:40,799 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=5156 minTime=5156 maxTime=5156
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=119 minTime=119 maxTime=119
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=891 minTime=891 maxTime=891

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 35.575 sec
Running org.cumulus4j.keystore.test.DateDependentKeyStrategyTest
10:55:40,819 DEBUG [main][DateDependentKeyStrategy] init: Calculated numberOfKeysToGenerate=5761
10:55:40,819 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:40,819 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:40,820 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:40,832 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:41,342 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:41,342 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@66b47c21
10:55:41,342 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@66b47c21
10:55:41,342 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b52e14d
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.258 sec
Running org.cumulus4j.keystore.test.KeyStorePropertyTest
10:55:43,083 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,084 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,084 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,091 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,109 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,109 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,110 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,116 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,131 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,131 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,131 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,137 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,145 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,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.
10:55:43,145 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,152 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,159 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,159 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,159 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,165 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.103 sec
Running org.cumulus4j.keystore.test.KeyStoreKeyTest
10:55:43,190 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
10:55:43,191 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,191 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,191 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,198 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,206 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
10:55:43,206 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:55:43,207 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,207 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,207 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,213 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,220 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:43,220 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26653222
10:55:43,226 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:43,226 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3532ec19
10:55:43,233 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:43,233 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68c4039c
10:55:43,239 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:43,239 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ae45eb6
10:55:43,246 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:43,246 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59f99ea
10:55:43,253 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,253 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,253 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,259 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,266 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:43,266 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,266 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,274 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:55:43,274 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:55:43,274 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:55:43,280 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:55:51,767 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@66c55599
10:55:51,767 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1fb2cf13
10:55:51,768 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@66c55599
10:55:51,768 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,769 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5a22edb0
10:55:51,769 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5a22edb0
10:55:51,769 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,769 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a62730
10:55:51,770 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4a03d3e0
10:55:51,770 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a62730
10:55:51,771 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,771 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@434057e4
10:55:51,771 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@434057e4
10:55:51,772 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,772 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ec27d51
10:55:51,772 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@396a541e
10:55:51,772 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ec27d51
10:55:51,774 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,774 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6d220c00
10:55:51,774 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2c9c8937
10:55:51,774 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6d220c00
10:55:51,775 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,776 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@31295100
10:55:51,776 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5a08299f
10:55:51,776 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@31295100
10:55:51,778 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,778 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@27376cd2
10:55:51,778 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@27376cd2
10:55:51,778 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f2a8c32
10:55:51,780 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,780 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@14706186
10:55:51,780 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@f2ff646
10:55:51,780 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26653222
10:55:51,784 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3532ec19
10:55:51,784 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@68c4039c
10:55:51,784 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ae45eb6
10:55:51,785 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59f99ea
10:55:51,785 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@14706186
10:55:51,786 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@13e25a8d
10:55:51,788 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28121fdf
10:55:51,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28121fdf
10:55:51,791 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:55:51,791 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3ff9370a
10:55:51,791 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3ff9370a
10:55:51,791 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f147b75
10:56:05,772 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:05,790 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:06,170 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:06,259 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:06,275 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:06,286 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:06,301 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:07,402 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
10:56:07,786 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=24121 minTime=24121 maxTime=24121
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=23912 minTime=0 maxTime=22
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=13 minTime=13 maxTime=13
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=371 minTime=371 maxTime=371

10:56:07,787 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:07,788 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:07,788 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:56:07,793 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:56:08,515 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:08,515 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ea36628
10:56:08,516 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76f764c4
10:56:08,516 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ea36628
10:56:19,542 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=9005 minTime=9005 maxTime=9005
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=83 minTime=83 maxTime=83
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2643 minTime=2643 maxTime=2643

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 36.362 sec
Running org.cumulus4j.keystore.test.KeyStoreCompatibilityTest
10:56:19,551 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:19,552 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:19,552 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:56:19,558 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:56:19,678 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c05c77
10:56:19,863 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:19,863 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
10:56:19,863 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:19,867 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:20,021 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:20,022 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
10:56:20,022 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:20,025 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:20,187 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:20,187 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:20,188 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:20,193 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:20,328 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:20,328 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:20,328 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:20,334 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:20,507 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:20,507 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
10:56:20,507 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:20,511 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:20,754 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:20,754 INFO  [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
10:56:20,754 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:20,758 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,038 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,038 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,038 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,045 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,282 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,283 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,283 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,288 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,583 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,584 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:56:21,584 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:56:21,584 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,586 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,594 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,595 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,595 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,595 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,597 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,605 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,605 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,605 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,606 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,608 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,619 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,619 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,619 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,619 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,622 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,633 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,633 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,633 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,634 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,636 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,643 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,644 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,644 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,644 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,646 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,654 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,654 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,654 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,654 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,656 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,667 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,667 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,667 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,668 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,670 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,682 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,682 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,682 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,683 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,685 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,695 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,695 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:56:21,695 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:56:21,696 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,698 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,704 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,705 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,705 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,705 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,707 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,714 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,714 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,714 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,715 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,717 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,727 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,727 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,728 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,728 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,730 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,741 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,741 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,741 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,741 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,744 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,751 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,751 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,751 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,751 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,754 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,761 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,761 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,761 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,761 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
10:56:21,763 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,773 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,774 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,774 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:56:21,774 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,776 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
10:56:21,787 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
10:56:21,787 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
10:56:21,787 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,787 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
10:56:21,790 INFO  [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.244 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CBC_PKCS5Padding_Test
10:56:21,799 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
10:56:21,801 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,801 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,801 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,807 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:21,813 WARN  [main][KeyStore] verifyMAC: MAC verification failed! macAlgorithm=HMACSHA1 expectedMAC=7b25aee7e9959cf638ecfdc2373279cecbe666a8 calculatedMAC=e733b836f1c1ec823dca8c4cdb91926e0b1ab365
10:56:21,814 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:56:21,814 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,814 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,815 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,821 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:21,827 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:21,827 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5622fdf
10:56:21,833 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:21,833 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4883b407
10:56:21,840 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:21,840 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d9d1a19
10:56:21,846 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:21,846 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c0f4a
10:56:21,853 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:21,853 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1794d431
10:56:21,862 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,862 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,862 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,868 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:21,874 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:21,875 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,875 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,883 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:21,883 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:21,883 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:21,889 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:34,416 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:34,606 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:56:37,043 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c6350c9
10:56:37,044 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,044 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39a4f94b
10:56:37,045 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74387bff
10:56:37,046 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39a4f94b
10:56:37,047 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7699a2eb
10:56:37,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8a51e9
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7699a2eb
10:56:37,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1e7a8e12
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@29aae9a1
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1e7a8e12
10:56:37,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@129f9885
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@40489626
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@129f9885
10:56:37,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a888a23
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74119d32
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a888a23
10:56:37,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a246885
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1ed92441
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a246885
10:56:37,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@50ce7c2b
10:56:37,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@284e830d
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@50ce7c2b
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1197a6c1
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@463b639e
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1197a6c1
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65377056
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65377056
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@393dd116
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@393dd116
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@15b0c95e
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@15b0c95e
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@88a6fe5
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@88a6fe5
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56610b62
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56610b62
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@183c5d9
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@183c5d9
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@126a168e
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@126a168e
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4944b661
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4944b661
10:56:37,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@17a09e30
10:56:37,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@17a09e30
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8922c10
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8922c10
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4a8e95ea
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4a8e95ea
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2615c564
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2615c564
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@35d79e88
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@35d79e88
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2cbcc68e
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2cbcc68e
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12233736
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12233736
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a15eef3
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a15eef3
10:56:37,051 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@13bf10e7
10:56:37,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@13bf10e7
10:56:37,052 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@597d3ca8
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@597d3ca8
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@52ec3a36
10:56:37,052 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@673b34ee
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c6828c6
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5622fdf
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4883b407
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d9d1a19
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@39c0f4a
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1794d431
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@673b34ee
10:56:37,052 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3cc0cd9f
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3cc0cd9f
10:56:37,052 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@f4e7dbb
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@f4e7dbb
10:56:37,052 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@316cb4f8
10:56:39,908 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:40,818 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:56:43,083 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,099 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,110 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,123 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,130 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,145 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,159 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,173 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,189 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,192 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,208 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,254 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,260 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,268 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:56:43,274 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:56:50,863 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
10:56:51,170 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=28976 minTime=28976 maxTime=28976
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=28776 minTime=0 maxTime=45
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=15 minTime=15 maxTime=15
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=290 minTime=290 maxTime=290

10:56:51,171 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:56:51,172 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:56:51,172 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
10:56:51,177 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:56:52,757 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:56:52,758 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c0f840b
10:56:52,758 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46991220
10:56:52,758 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c0f840b
10:57:01,787 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=8369 minTime=8369 maxTime=8369
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=98 minTime=98 maxTime=98
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=2124 minTime=2124 maxTime=2124

Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 39.997 sec
Running org.cumulus4j.keystore.test.LongPropertyTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.018 sec
Running org.cumulus4j.keystore.test.KeyStoreUserTest
10:57:01,820 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,820 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,820 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,826 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,852 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
10:57:01,852 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:57:01,853 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,853 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,853 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,859 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,860 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,860 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,860 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,866 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,879 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,879 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,879 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,885 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,886 WARN  [main][KeyStore] getMasterKey: Unknown userName: null
10:57:01,887 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,887 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,887 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,893 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,899 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
10:57:01,899 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:57:01,900 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,900 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,900 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,906 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,925 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:01,925 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@343f4d3d
10:57:01,926 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,926 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,926 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,932 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,950 DEBUG [main][CachedMasterKey] clear: Clearing for userName='bieber'.
10:57:01,950 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53b32d7
10:57:01,951 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,952 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:01,952 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,958 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:01,977 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:01,977 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5442a311
10:57:01,979 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:01,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.
10:57:01,979 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
10:57:01,985 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
10:57:02,023 WARN  [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "bieber"!
10:57:02,023 WARN  [main][KeyStore] getMasterKey: Wrong password for user "bieber"! MAC verification failed.
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.213 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CFB_NoPadding_Test
10:57:02,030 WARN  [main][KeyStore] getMasterKey: Unknown userName: marco
10:57:02,031 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:02,031 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:02,031 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:02,037 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:02,044 WARN  [main][KeyStore] verifyMAC: MAC verification failed! macAlgorithm=HMACSHA1 expectedMAC=523248d77eb89330e721ac60f979b212cdb7c2da calculatedMAC=39410dd13715a563b388baf8d297a181290e3b90
10:57:02,044 WARN  [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
10:57:02,044 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:02,044 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:02,045 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:02,050 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:02,057 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:02,057 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@548e7350
10:57:02,063 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:02,063 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a968a59
10:57:02,069 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:02,069 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4667ae56
10:57:02,075 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:02,075 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77cd7a0
10:57:02,081 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:02,082 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@204f30ec
10:57:02,088 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:02,088 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:02,088 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:02,095 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:02,101 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:02,102 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:02,102 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:02,109 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:02,109 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:02,109 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:02,115 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:07,416 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:07,788 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:57:16,900 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:57:19,551 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:57:19,863 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,022 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,187 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,329 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,507 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,754 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:20,848 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3ac61ced
10:57:20,849 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,849 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e59b439
10:57:20,849 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e765a25
10:57:20,849 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@60f05dd9
10:57:20,849 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e59b439
10:57:20,851 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6a5b045e
10:57:20,852 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,852 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3ac61ced
10:57:20,852 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@49809d50
10:57:20,853 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@157b5137
10:57:20,854 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7787c8d4
10:57:20,855 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
10:57:20,855 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45cc4b8e
10:57:20,855 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@86b347a
10:57:20,855 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@343f4d3d
10:57:20,856 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45cc4b8e
10:57:20,857 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,857 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@47d63ea9
10:57:20,857 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e5e8fef
10:57:20,857 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@47d63ea9
10:57:20,857 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53b32d7
10:57:20,858 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
10:57:20,858 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@29e93591
10:57:20,858 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@78f00c32
10:57:20,859 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5442a311
10:57:20,859 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@29e93591
10:57:20,860 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ea556f3
10:57:20,860 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74fe813f
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@145b778c
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74fe813f
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c3268a9
10:57:20,860 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@ea556f3
10:57:20,862 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4804b912
10:57:20,863 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48733e54
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7dbd7c1
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@548e7350
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a968a59
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4667ae56
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77cd7a0
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@204f30ec
10:57:20,864 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48733e54
10:57:20,865 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@785390b3
10:57:20,866 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,866 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@15f27a82
10:57:20,866 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@15f27a82
10:57:20,867 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:20,867 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@442267f6
10:57:20,867 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@442267f6
10:57:20,867 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1d1ed56c
10:57:21,038 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,282 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,577 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,591 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,602 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,614 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,626 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,640 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,650 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,662 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,675 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,690 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,701 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,712 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,721 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,735 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,748 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,758 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,767 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,782 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,799 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,800 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,814 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,862 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,868 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,875 INFO  [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
10:57:21,885 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:57:29,891 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
10:57:30,163 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys): 
    00.generateManyKeysIndividually invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=27776 minTime=27776 maxTime=27776
    05.generateOneKey invocationCount=5000
        recursionLevel=0 invocationCount=5000 accumulatedTime=27512 minTime=0 maxTime=49
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=12 minTime=12 maxTime=12
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=259 minTime=259 maxTime=259

10:57:30,165 INFO  [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
10:57:30,165 INFO  [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
10:57:30,165 INFO  [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
10:57:30,171 INFO  [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
10:57:31,374 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
10:57:31,374 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@792c0874
10:57:31,374 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7f8c5bb9
10:57:31,374 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@792c0874
10:57:34,608 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
10:57:38,769 INFO  [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys): 
    00.generateManyKeysBulk invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=6874 minTime=6874 maxTime=6874
    07.readKeyStore invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=86 minTime=86 maxTime=86
    10.readManyKeys invocationCount=1
        recursionLevel=0 invocationCount=1 accumulatedTime=1618 minTime=1618 maxTime=1618

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