[INFO]
[INFO] ------------------------------------------------------------------------
[INFO] Building org.cumulus4j.keystore 1.2.1-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.4.1:clean (default-clean) @ org.cumulus4j.keystore ---
[INFO] Deleting /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target
[INFO] Deleting /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore (includes = [*.log, $jarplug.work/**], excludes = [])
[INFO]
[INFO] --- maven-resources-plugin:2.5:resources (default-resources) @ org.cumulus4j.keystore ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/src/main/resources
[INFO] Copying 1 resource
[INFO] Copying 0 resource
[INFO]
[INFO] --- maven-compiler-plugin:2.3.2:compile (default-compile) @ org.cumulus4j.keystore ---
[INFO] Compiling 27 source files to /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/classes
[INFO]
[INFO] --- maven-resources-plugin:2.5:testResources (default-testResources) @ org.cumulus4j.keystore ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 19 resources
[INFO]
[INFO] --- maven-compiler-plugin:2.3.2:testCompile (default-testCompile) @ org.cumulus4j.keystore ---
[INFO] Compiling 12 source files to /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/test-classes
[INFO]
[INFO] --- maven-surefire-plugin:2.8.1:test (default-test) @ org.cumulus4j.keystore ---
[INFO] Surefire report directory: /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.keystore/target/surefire-reports
-------------------------------------------------------
T E S T S
-------------------------------------------------------
Running org.cumulus4j.keystore.test.KeyStorePropertyTest
18:16:16,906 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:16,909 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:16,912 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,281 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
18:16:17,281 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES"
18:16:17,282 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
18:16:17,283 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES64MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES64"
18:16:17,284 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DES/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
18:16:17,284 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESMAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DESCFB8"
18:16:17,285 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
18:16:17,286 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="DESWITHISO9797MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
18:16:17,286 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
18:16:17,286 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3"
18:16:17,289 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3WITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
18:16:17,289 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="ISO9797ALG3MACWITHISO7816-4PADDING" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$DES9797Alg3with7816d4"
18:16:17,290 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
18:16:17,290 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2"
18:16:17,291 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
18:16:17,292 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="RC2MAC/CFB8" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RC2CFB8"
18:16:17,293 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
18:16:17,293 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="GOST28147MAC" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$GOST28147"
18:16:17,296 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA384"
18:16:17,297 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="OLDHMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$OldSHA512"
18:16:17,298 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
18:16:17,298 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
18:16:17,299 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD2" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD2"
18:16:17,300 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
18:16:17,300 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
18:16:17,300 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD4" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD4"
18:16:17,301 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACMD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
18:16:17,301 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
18:16:17,302 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/MD5" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$MD5"
18:16:17,303 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
18:16:17,303 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
18:16:17,303 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA1" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA1"
18:16:17,304 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
18:16:17,305 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
18:16:17,305 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA224" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA224"
18:16:17,306 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
18:16:17,306 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
18:16:17,306 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA256" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA256"
18:16:17,308 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
18:16:17,308 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
18:16:17,308 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA384" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA384"
18:16:17,309 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACSHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
18:16:17,309 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
18:16:17,309 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/SHA512" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$SHA512"
18:16:17,311 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
18:16:17,311 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
18:16:17,311 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD128" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD128"
18:16:17,312 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACRIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
18:16:17,312 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
18:16:17,313 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/RIPEMD160" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$RIPEMD160"
18:16:17,314 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMACTIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
18:16:17,314 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC-TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
18:16:17,315 TRACE [main][CryptoRegistry] registerMACCalculatorFactory: algorithmName="HMAC/TIGER" factoryClass="org.cumulus4j.crypto.internal.mac.MACCalculatorFactoryImpl$Tiger"
18:16:17,321 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="ElGamal" engineClass="org.bouncycastle.crypto.engines.ElGamalEngine"
18:16:17,326 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="NaccacheStern" engineClass="org.bouncycastle.crypto.engines.NaccacheSternEngine"
18:16:17,330 TRACE [main][CryptoRegistry] registerAsymmetricEngineClass: algorithmName="RSA" engineClass="org.bouncycastle.crypto.engines.RSABlindedEngine"
18:16:17,338 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES" engineClass="org.bouncycastle.crypto.engines.AESEngine"
18:16:17,349 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.fast" engineClass="org.bouncycastle.crypto.engines.AESFastEngine"
18:16:17,353 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="AES.light" engineClass="org.bouncycastle.crypto.engines.AESLightEngine"
18:16:17,359 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Blowfish" engineClass="org.bouncycastle.crypto.engines.BlowfishEngine"
18:16:17,365 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia" engineClass="org.bouncycastle.crypto.engines.CamelliaEngine"
18:16:17,370 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Camellia.light" engineClass="org.bouncycastle.crypto.engines.CamelliaLightEngine"
18:16:17,380 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST5" engineClass="org.bouncycastle.crypto.engines.CAST5Engine"
18:16:17,382 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="CAST6" engineClass="org.bouncycastle.crypto.engines.CAST6Engine"
18:16:17,388 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DESede" engineClass="org.bouncycastle.crypto.engines.DESedeEngine"
18:16:17,388 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="DES" engineClass="org.bouncycastle.crypto.engines.DESEngine"
18:16:17,395 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GOST28147" engineClass="org.bouncycastle.crypto.engines.GOST28147Engine"
18:16:17,398 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Noekeon" engineClass="org.bouncycastle.crypto.engines.NoekeonEngine"
18:16:17,400 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Null" engineClass="org.bouncycastle.crypto.engines.NullEngine"
18:16:17,404 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC2" engineClass="org.bouncycastle.crypto.engines.RC2Engine"
18:16:17,409 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-32" engineClass="org.bouncycastle.crypto.engines.RC532Engine"
18:16:17,411 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC5-64" engineClass="org.bouncycastle.crypto.engines.RC564Engine"
18:16:17,413 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC6" engineClass="org.bouncycastle.crypto.engines.RC6Engine"
18:16:17,419 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Rijndael" engineClass="org.bouncycastle.crypto.engines.RijndaelEngine"
18:16:17,424 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SEED" engineClass="org.bouncycastle.crypto.engines.SEEDEngine"
18:16:17,431 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Serpent" engineClass="org.bouncycastle.crypto.engines.SerpentEngine"
18:16:17,434 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="SKIPJACK" engineClass="org.bouncycastle.crypto.engines.SkipjackEngine"
18:16:17,436 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="TEA" engineClass="org.bouncycastle.crypto.engines.TEAEngine"
18:16:17,442 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Twofish" engineClass="org.bouncycastle.crypto.engines.TwofishEngine"
18:16:17,444 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="XTEA" engineClass="org.bouncycastle.crypto.engines.XTEAEngine"
18:16:17,448 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Grain-128" engineClass="org.bouncycastle.crypto.engines.Grain128Engine"
18:16:17,451 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="GRAIN-V1" engineClass="org.bouncycastle.crypto.engines.Grainv1Engine"
18:16:17,453 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-128" engineClass="org.bouncycastle.crypto.engines.HC128Engine"
18:16:17,455 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="HC-256" engineClass="org.bouncycastle.crypto.engines.HC256Engine"
18:16:17,458 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="ISAAC" engineClass="org.bouncycastle.crypto.engines.ISAACEngine"
18:16:17,460 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="RC4" engineClass="org.bouncycastle.crypto.engines.RC4Engine"
18:16:17,467 TRACE [main][CryptoRegistry] registerSymmetricEngineClass: algorithmName="Salsa20" engineClass="org.bouncycastle.crypto.engines.Salsa20Engine"
18:16:17,468 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CBC" modeClass="org.bouncycastle.crypto.modes.CBCBlockCipher"
18:16:17,471 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="CCM" modeClass="org.bouncycastle.crypto.modes.CCMBlockCipher"
18:16:17,475 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,475 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,475 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,476 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,477 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,478 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,479 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,479 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,479 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,479 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,479 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="CFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCFBBlockCipher"
18:16:17,481 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CTS" modeClass="org.bouncycastle.crypto.modes.CTSBlockCipher"
18:16:17,482 TRACE [main][CryptoRegistry] registerBufferedBlockCipherMode: modeName="CBC-CTS" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jCBCCTSBlockCipher"
18:16:17,484 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="EAX" modeClass="org.bouncycastle.crypto.modes.EAXBlockCipher"
18:16:17,486 TRACE [main][CryptoRegistry] registerAEADBlockCipherMode: modeName="GCM" modeClass="org.bouncycastle.crypto.modes.GCMBlockCipher"
18:16:17,487 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="GOFB" modeClass="org.bouncycastle.crypto.modes.GOFBBlockCipher"
18:16:17,490 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,490 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB8" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,491 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB16" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,491 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB24" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,491 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB32" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,492 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB40" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,492 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB48" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,492 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB56" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,492 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB64" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,493 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB72" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,493 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB80" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,493 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB88" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,493 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB96" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,494 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB104" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,494 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB112" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,494 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB120" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,494 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB128" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,495 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB136" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,495 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB144" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,495 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB152" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,495 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB160" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,495 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB168" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,496 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB176" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,496 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB184" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,496 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB192" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,496 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB200" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,497 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB208" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,497 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB216" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,497 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB224" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,497 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB232" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,498 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB240" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,498 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB248" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,498 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="OFB256" modeClass="org.cumulus4j.crypto.internal.symmetric.mode.C4jOFBBlockCipher"
18:16:17,500 TRACE [main][CryptoRegistry] registerBlockCipherMode: modeName="SIC" modeClass="org.bouncycastle.crypto.modes.SICBlockCipher"
18:16:17,526 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126-2" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
18:16:17,527 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO10126" paddingClass="org.bouncycastle.crypto.paddings.ISO10126d2Padding"
18:16:17,528 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ISO7816-4" paddingClass="org.bouncycastle.crypto.paddings.ISO7816d4Padding"
18:16:17,530 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS7" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
18:16:17,530 TRACE [main][CryptoRegistry] registerBlockCipherPadding: paddingName="PKCS5" paddingClass="org.bouncycastle.crypto.paddings.PKCS7Padding"
18:16:17,532 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="TBC" paddingClass="org.bouncycastle.crypto.paddings.TBCPadding"
18:16:17,534 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="X9.23" paddingClass="org.bouncycastle.crypto.paddings.X923Padding"
18:16:17,535 DEBUG [main][CryptoRegistry] registerBlockCipherPadding: paddingName="ZeroByte" paddingClass="org.bouncycastle.crypto.paddings.ZeroBytePadding"
18:16:17,537 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="ISO9796-1" paddingClass="org.bouncycastle.crypto.encodings.ISO9796d1Encoding"
18:16:17,539 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEP" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
18:16:17,539 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="OAEPWITHSHA1ANDMGF1" paddingClass="org.bouncycastle.crypto.encodings.OAEPEncoding"
18:16:17,541 TRACE [main][CryptoRegistry] registerAsymmetricBlockCipherPadding: paddingName="PKCS1" paddingClass="org.bouncycastle.crypto.encodings.PKCS1Encoding"
18:16:17,558 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DH" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DHBasicKeyPairGeneratorFactory"
18:16:17,559 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="DSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.DSAKeyPairGeneratorFactory"
18:16:17,560 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="ElGamal" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.ElGamalKeyPairGeneratorFactory"
18:16:17,561 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="GOST3410" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.GOST3410KeyPairGeneratorFactory"
18:16:17,563 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="NaccacheStern" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.NaccacheSternKeyPairGeneratorFactory"
18:16:17,567 TRACE [main][CryptoRegistry] registerAsymmetricCipherKeyPairGeneratorFactory: algorithmName="RSA" factoryClass="org.cumulus4j.crypto.internal.asymmetric.keypairgenerator.RSAKeyPairGeneratorFactory"
18:16:17,580 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:17,632 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:17,633 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:17,633 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,672 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:17,700 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:17,700 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:17,700 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,720 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:17,778 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:17,779 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:17,779 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,788 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:17,824 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:17,824 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:17,825 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,838 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: 1.991 sec
Running org.cumulus4j.keystore.test.DateDependentKeyStrategyTest
18:16:17,956 DEBUG [main][DateDependentKeyStrategy] init: Calculated numberOfKeysToGenerate=5761
18:16:17,962 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:17,963 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:17,963 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:17,970 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:18,034 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@541d511a
18:16:18,039 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@50b7ad1a
18:16:18,040 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,040 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@541d511a
18:16:18,040 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ac5af5c
18:16:18,041 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@787be75d
18:16:18,041 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,041 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@787be75d
18:16:18,041 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e92c877
18:16:18,042 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46a7041a
18:16:18,042 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,043 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e92c877
18:16:18,043 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7efe6b32
18:16:18,043 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,044 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7efe6b32
18:16:18,045 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@712fe0c0
18:16:18,045 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7e4c974d
18:16:18,045 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,046 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@712fe0c0
18:16:18,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56618102
18:16:18,047 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@56618102
18:16:18,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1b5cd69c
18:16:18,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c0da9cc
18:16:18,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1b5cd69c
18:16:18,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7506c77f
18:16:18,050 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10139edf
18:16:18,050 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:18,051 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7506c77f
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.144 sec
Running org.cumulus4j.keystore.test.KeyStoreUserTest
18:16:22,118 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,119 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,119 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,129 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,159 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
18:16:22,159 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:16:22,162 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,162 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,163 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,169 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,170 WARN [main][KeyStore] getMasterKey: Unknown userName: null
18:16:22,175 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,175 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,175 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,181 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,184 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,185 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,185 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,191 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,206 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,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.
18:16:22,207 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,213 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,220 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
18:16:22,220 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:16:22,223 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,223 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,223 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,229 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,248 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,248 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f1b95f
18:16:22,251 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,251 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,251 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,257 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,275 DEBUG [main][CachedMasterKey] clear: Clearing for userName='bieber'.
18:16:22,275 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@19c10e82
18:16:22,278 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,278 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,278 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,284 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,303 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,303 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@519f8603
18:16:22,306 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,307 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,307 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:16:22,313 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:16:22,351 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "bieber"!
18:16:22,352 WARN [main][KeyStore] getMasterKey: Wrong password for user "bieber"! MAC verification failed.
Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.251 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_AES_CBC_PKCS5Padding_Test
18:16:22,375 WARN [main][KeyStore] getMasterKey: Unknown userName: marco
18:16:22,378 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,378 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,379 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:22,389 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:22,403 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
18:16:22,403 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:16:22,406 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,406 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,406 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:22,413 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:22,421 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,422 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3626a69f
18:16:22,428 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,429 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@23852c7f
18:16:22,438 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,438 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c2c5810
18:16:22,445 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,445 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4349c220
18:16:22,455 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:22,455 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f67e563
18:16:22,469 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,469 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,469 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:22,475 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:22,484 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:22,485 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:22,486 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,500 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:22,501 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:22,501 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:22,507 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:45,116 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,116 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51b7ecdb
18:16:45,116 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@53a15ae7
18:16:45,117 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@353180dc
18:16:45,118 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@51b7ecdb
18:16:45,119 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26419cb3
18:16:45,120 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4436f0c9
18:16:45,121 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,121 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f933da1
18:16:45,121 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8d6a3d1
18:16:45,121 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f933da1
18:16:45,122 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4fd00cff
18:16:45,123 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
18:16:45,123 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16ade920
18:16:45,123 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@24456977
18:16:45,123 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f1b95f
18:16:45,124 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16ade920
18:16:45,124 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,124 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12b7530
18:16:45,124 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4c1aa2e9
18:16:45,124 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12b7530
18:16:45,124 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@19c10e82
18:16:45,125 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
18:16:45,125 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76c4db49
18:16:45,125 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7618d289
18:16:45,125 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@519f8603
18:16:45,125 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76c4db49
18:16:45,125 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='bieber'.
18:16:45,125 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b1fff2b
18:16:45,126 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a996ff
18:16:45,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@24f84033
18:16:45,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1a996ff
18:16:45,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@263ee73b
18:16:45,126 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b1fff2b
18:16:45,128 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@36c0b403
18:16:45,128 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,128 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65e7deb6
18:16:45,128 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@59ceb2fe
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3626a69f
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@23852c7f
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7c2c5810
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4349c220
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1f67e563
18:16:45,129 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@65e7deb6
18:16:45,130 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6c48326b
18:16:45,130 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,130 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2f13f599
18:16:45,130 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2f13f599
18:16:45,135 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:45,135 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b8628c8
18:16:45,135 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b8628c8
18:16:45,135 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@64df80ff
18:16:54,973 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
18:16:55,316 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys):
00.generateManyKeysIndividually invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=32462 minTime=32462 maxTime=32462
05.generateOneKey invocationCount=5000
recursionLevel=0 invocationCount=5000 accumulatedTime=32381 minTime=1 maxTime=28
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=128 minTime=128 maxTime=128
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=213 minTime=213 maxTime=213
18:16:55,318 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:16:55,319 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:16:55,319 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:16:55,324 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:16:57,066 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:57,066 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@43e09e6a
18:16:57,067 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@43e09e6a
18:16:57,098 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:16:57,098 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76022cac
18:16:57,098 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@76022cac
18:16:57,098 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8c30cbb
18:17:02,186 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys):
00.generateManyKeysBulk invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=5491 minTime=5491 maxTime=5491
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=193 minTime=193 maxTime=193
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=1160 minTime=1160 maxTime=1160
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 39.826 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CFB_NoPadding_Test
18:17:02,206 WARN [main][KeyStore] getMasterKey: Unknown userName: marco
18:17:02,209 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:02,209 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:02,209 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:02,215 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:02,221 WARN [main][KeyStore] verifyMAC: MAC verification failed! macAlgorithm=HMACSHA1 expectedMAC=2a1530471eb387030974f2227520a42982cd729b calculatedMAC=f940c39602ca9a8e3f69e3fb79514bbddafc7982
18:17:02,222 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:17:02,224 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:02,224 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:02,224 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:02,230 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:02,236 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:02,236 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4e021bd
18:17:02,242 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:02,242 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16c701cb
18:17:02,249 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:02,249 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6346c21b
18:17:02,254 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:02,255 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a71ad88
18:17:02,261 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:02,261 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d37d9b1
18:17:02,269 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:02,269 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:02,269 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:02,275 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:02,282 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:02,282 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:02,283 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:02,292 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:02,292 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:02,293 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:02,298 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:03,043 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@235497e0
18:17:03,043 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6bf053f
18:17:03,044 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:03,045 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6bf053f
18:17:03,045 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7491616e
18:17:03,047 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:03,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7491616e
18:17:03,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@e38e978
18:17:03,047 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a67cc93
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7d37d9b1
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a71ad88
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6346c21b
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@16c701cb
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4e021bd
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74a7e4d9
18:17:03,048 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a67cc93
18:17:03,048 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3aa19e64
18:17:03,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48a5d54e
18:17:03,049 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:03,049 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48a5d54e
18:17:16,901 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,632 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,700 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,752 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,778 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,810 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,824 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,856 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:17,953 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
18:17:22,118 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,162 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,175 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,184 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,206 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,223 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,251 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,278 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,306 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,376 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,378 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,406 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,469 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,479 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,488 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:22,499 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:33,811 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
18:17:34,225 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys):
00.generateManyKeysIndividually invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=31512 minTime=31512 maxTime=31512
05.generateOneKey invocationCount=5000
recursionLevel=0 invocationCount=5000 accumulatedTime=31448 minTime=0 maxTime=79
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=16 minTime=16 maxTime=16
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=397 minTime=397 maxTime=397
18:17:34,227 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:34,228 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:34,228 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:34,234 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:17:35,702 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10ef3b8b
18:17:40,923 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ad4513
18:17:40,924 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:40,924 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ad4513
18:17:40,924 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@69644a50
18:17:40,924 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:40,924 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@69644a50
18:17:40,925 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1cfd02ce
18:17:40,925 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:40,925 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1cfd02ce
18:17:40,925 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26c33140
18:17:40,925 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:40,925 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@26c33140
18:17:46,124 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys):
00.generateManyKeysBulk invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=9316 minTime=9316 maxTime=9316
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=106 minTime=106 maxTime=106
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=2450 minTime=2450 maxTime=2450
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 43.93 sec
Running org.cumulus4j.keystore.test.KeyStoreCompatibilityTest
18:17:46,155 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,155 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:46,155 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:46,155 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:46,158 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,166 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,167 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,167 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,168 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,171 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,179 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,180 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,180 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,180 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,183 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,194 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,194 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,194 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,195 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,197 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,208 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,208 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,208 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,208 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,211 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,219 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,219 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,220 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,220 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,222 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,231 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,232 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,232 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,233 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,236 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,247 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,247 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,247 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,248 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,250 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,261 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,262 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,262 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,262 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,265 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,275 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,275 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:46,276 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:46,276 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:46,278 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,286 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,286 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,286 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,286 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,288 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,296 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,297 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,297 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,297 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,299 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,310 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,310 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,310 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,310 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,313 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,323 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,324 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,324 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,324 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,327 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,334 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,334 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,335 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,335 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,337 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,345 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,345 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,345 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,346 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,348 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,359 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,359 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,359 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,359 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,362 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,373 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: Reference-KeyStore was successfully opened.
18:17:46,373 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:46,373 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:46,373 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:46,376 INFO [main][KeyStoreCompatibilityTest] openReferenceKeystore: New key was successfully generated.
18:17:46,378 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:46,378 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:46,379 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:46,384 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:46,840 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:46,840 INFO [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
18:17:46,840 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:46,843 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:47,036 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:47,036 INFO [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
18:17:47,036 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:47,039 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:47,252 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:47,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.
18:17:47,253 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CFB/NoPadding'. Using this encryption algorithm.
18:17:47,258 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:47,440 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:47,440 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:47,440 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'AES/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:47,446 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:47,669 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:47,669 INFO [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
18:17:47,670 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:47,673 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:47,719 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,719 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4276f4a9
18:17:47,719 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,719 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@fb8a556
18:17:47,719 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@fb8a556
18:17:47,720 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,720 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a3e8692
18:17:47,720 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3a3e8692
18:17:47,721 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,721 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@250f0f9f
18:17:47,721 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@250f0f9f
18:17:47,722 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,722 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5081a2e1
18:17:47,722 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5081a2e1
18:17:47,722 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b9e41fb
18:17:47,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6b9e41fb
18:17:47,723 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10e8e1af
18:17:47,723 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@10e8e1af
18:17:47,724 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,724 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48e18517
18:17:47,725 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@48e18517
18:17:47,725 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,725 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f45abe3
18:17:47,726 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6f45abe3
18:17:47,726 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,726 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ed2fe7
18:17:47,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@77ed2fe7
18:17:47,727 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74e42433
18:17:47,727 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@74e42433
18:17:47,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4276f4a9
18:17:47,728 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,728 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3cfbc9c9
18:17:47,729 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3cfbc9c9
18:17:47,729 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,729 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3acc0a7c
18:17:47,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3acc0a7c
18:17:47,730 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,730 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1fd9e040
18:17:47,731 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1fd9e040
18:17:47,731 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,731 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@529b2735
18:17:47,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@529b2735
18:17:47,732 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,732 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c1d913b
18:17:47,733 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@c1d913b
18:17:47,733 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,733 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@33a70fb5
18:17:47,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1cf84e91
18:17:47,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@33a70fb5
18:17:47,734 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,734 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@71ee2072
18:17:47,735 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@71ee2072
18:17:47,735 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,735 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@489456cd
18:17:47,735 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ced1f5
18:17:47,735 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@489456cd
18:17:47,736 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@583a2d43
18:17:47,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5371b6f5
18:17:47,736 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@583a2d43
18:17:47,737 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ea2b99f
18:17:47,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@1d489e0e
18:17:47,737 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ea2b99f
18:17:47,738 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a80b827
18:17:47,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@8ba0dd4
18:17:47,738 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@a80b827
18:17:47,738 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:47,739 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ee1ddc5
18:17:47,739 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2ea6e962
18:17:47,739 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ee1ddc5
18:17:47,943 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 128 bit. Using this key size.
18:17:47,943 INFO [main][KeyStore] createUser: Created master-key with a size of 128 bits. This key will not be modified for this key-store anymore.
18:17:47,943 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:47,946 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:48,259 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:48,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.
18:17:48,260 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CFB/NoPadding'. Using this encryption algorithm.
18:17:48,265 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
18:17:48,549 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is set to 256 bit. Using this key size.
18:17:48,550 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:48,550 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:17:48,555 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMAC/SHA1'. Using this MAC algorithm.
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.751 sec
Running org.cumulus4j.keystore.test.KeyStoreKeyTest
18:17:48,899 WARN [main][KeyStore] getMasterKey: Unknown userName: marco
18:17:48,901 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:48,901 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:48,901 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:48,907 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:48,913 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
18:17:48,913 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:17:48,915 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:48,915 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:48,915 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:48,921 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:48,927 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:48,927 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46028049
18:17:48,933 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:48,933 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4e345c87
18:17:48,939 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:48,939 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4fa6cb2d
18:17:48,945 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:48,945 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f652035
18:17:48,951 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:48,952 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7825a04b
18:17:48,960 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:48,960 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:48,960 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:48,966 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:48,972 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:48,972 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:48,973 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:48,982 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:17:48,982 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:17:48,982 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:17:48,988 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:17:50,629 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,629 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@515441f
18:17:50,629 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@372b51d4
18:17:50,630 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@515441f
18:17:50,630 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,630 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ecbd0a2
18:17:50,630 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ee7482
18:17:50,630 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6ecbd0a2
18:17:50,631 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,631 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12768ace
18:17:50,631 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2492e3da
18:17:50,631 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@12768ace
18:17:50,632 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e124212
18:17:50,632 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,632 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@70afe432
18:17:50,632 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@2baec86a
18:17:50,632 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46028049
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4e345c87
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4fa6cb2d
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4f652035
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7825a04b
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@70afe432
18:17:50,633 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@674da52b
18:17:50,634 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,634 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@270cd582
18:17:50,634 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@270cd582
18:17:50,635 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:17:50,635 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b955dd5
18:17:50,635 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b955dd5
18:17:50,635 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b436982
18:17:55,103 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:17:55,318 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:01,026 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,207 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,208 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,224 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,269 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,276 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,284 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:02,292 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:14,741 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
18:18:15,404 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys):
00.generateManyKeysIndividually invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=25753 minTime=25753 maxTime=25753
05.generateOneKey invocationCount=5000
recursionLevel=0 invocationCount=5000 accumulatedTime=25738 minTime=0 maxTime=29
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=15 minTime=15 maxTime=15
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=648 minTime=648 maxTime=648
18:18:15,406 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:15,407 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:18:15,407 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
18:18:15,412 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
18:18:15,471 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3170679d
18:18:15,471 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7609e871
18:18:15,472 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:15,472 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7609e871
18:18:17,953 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:30,784 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@30951aeb
18:18:30,785 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:30,785 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@30951aeb
18:18:30,785 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b4d8840
18:18:30,788 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:30,788 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3b4d8840
18:18:30,788 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5228b636
18:18:30,788 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:30,788 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5228b636
18:18:30,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4d8e5d82
18:18:30,789 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:30,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4d8e5d82
18:18:30,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d9d2995
18:18:30,789 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:30,789 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3d9d2995
18:18:33,827 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:34,227 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:34,625 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys):
00.generateManyKeysBulk invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=14305 minTime=14305 maxTime=14305
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=96 minTime=96 maxTime=96
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=4793 minTime=4793 maxTime=4793
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 45.735 sec
Running org.cumulus4j.keystore.test.KeyStoreKey_Twofish_CBC_PKCS5Padding_Test
18:18:34,640 WARN [main][KeyStore] getMasterKey: Unknown userName: marco
18:18:34,642 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:34,642 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:18:34,642 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:18:34,648 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:18:34,654 WARN [main][KeyStore] getMasterKey: Caught CryptoException indicating a wrong password for user "marco"!
18:18:34,654 WARN [main][KeyStore] getMasterKey: Wrong password for user "marco"! MAC verification failed.
18:18:34,656 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:34,656 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:18:34,656 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:18:34,662 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:18:34,668 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:34,668 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3e572b61
18:18:34,673 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:34,673 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ce10d40
18:18:34,679 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:34,680 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b2d0c2e
18:18:34,685 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:34,685 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7a7ac690
18:18:34,692 DEBUG [main][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:34,692 DEBUG [main][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@128256c2
18:18:34,700 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:34,700 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:18:34,700 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:18:34,706 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:18:34,712 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:18:34,712 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:18:34,712 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:34,720 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:18:34,721 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:18:34,721 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:18:34,726 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:18:43,675 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,148 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,162 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,176 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,188 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,202 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,215 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,227 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,241 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,255 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,269 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,283 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,293 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,304 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,318 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,331 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,342 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,353 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,367 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,378 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:46,840 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:47,037 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:47,252 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:47,440 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:47,670 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:47,943 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,259 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,549 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,899 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,901 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,915 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,960 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,967 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,974 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:48,983 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:18:57,439 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3f332b09
18:18:57,440 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28c1b980
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@46799767
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@3e572b61
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ce10d40
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4b2d0c2e
18:18:57,440 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@7a7ac690
18:18:57,441 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@128256c2
18:18:57,441 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@28c1b980
18:18:57,441 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e2404a0
18:18:57,442 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:57,442 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45317223
18:18:57,442 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@45317223
18:18:57,442 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:18:57,443 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79c45dbe
18:18:57,443 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@79c45dbe
18:18:57,443 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@4ae6a366
18:19:06,047 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually: firstKeyID=1 lastKeyID=5000
18:19:06,472 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysIndividually (5000 keys):
00.generateManyKeysIndividually invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=31320 minTime=31320 maxTime=31320
05.generateOneKey invocationCount=5000
recursionLevel=0 invocationCount=5000 accumulatedTime=31311 minTime=0 maxTime=27
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=16 minTime=16 maxTime=16
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=409 minTime=409 maxTime=409
18:19:06,474 INFO [main][KeyStore] getKeySize: System property 'cumulus4j.KeyStore.keySize' is not set. Using default key size (256 bit).
18:19:06,474 INFO [main][KeyStore] createUser: Created master-key with a size of 256 bits. This key will not be modified for this key-store anymore.
18:19:06,475 INFO [main][KeyStore] getEncryptionAlgorithm: System property 'cumulus4j.KeyStore.encryptionAlgorithm' is set to 'Twofish/CBC/PKCS5Padding'. Using this encryption algorithm.
18:19:06,480 INFO [main][KeyStore] getMACAlgorithm: System property 'cumulus4j.KeyStore.macAlgorithm' is set to 'HMACSHA1'. Using this MAC algorithm.
18:19:07,385 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:19:07,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5ef80a07
18:19:07,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@6e5a8246
18:19:07,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@5ef80a07
18:19:07,386 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@781efa43
18:19:07,387 DEBUG [Finalizer][CachedMasterKey] clear: Clearing for userName='marco'.
18:19:07,387 DEBUG [Finalizer][MasterKey] clear: Clearing org.cumulus4j.keystore.MasterKey@781efa43
18:19:14,759 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:19:15,406 INFO [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: KeyStore has been garbage-collected. Removing this ExipreCacheEntryTimerTask.
18:19:19,885 INFO [main][KeyStoreKeyTest] generateAndReadManyKeysBulk (35000 keys):
00.generateManyKeysBulk invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=10406 minTime=10406 maxTime=10406
07.readKeyStore invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=108 minTime=108 maxTime=108
10.readManyKeys invocationCount=1
recursionLevel=0 invocationCount=1 accumulatedTime=2872 minTime=2872 maxTime=2872
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 45.256 sec
Running org.cumulus4j.keystore.test.LongPropertyTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.014 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 = []
Console Output
Help us localize this page