Details
Description
We have seen some intermittent failures of this test:
Error Message
java.lang.AssertionError
Stack Trace
java.lang.AssertionError: Should not have been able to reencryptEncryptedKey at org.junit.Assert.fail(Assert.java:88) at org.apache.hadoop.crypto.key.kms.server.TestKMS$11$15.run(TestKMS.java:1616) at org.apache.hadoop.crypto.key.kms.server.TestKMS$11$15.run(TestKMS.java:1608) at java.security.AccessController.doPrivileged(Native Method) at javax.security.auth.Subject.doAs(Subject.java:415) at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1917) at org.apache.hadoop.crypto.key.kms.server.TestKMS.doAs(TestKMS.java:313) at org.apache.hadoop.crypto.key.kms.server.TestKMS.access$100(TestKMS.java:97)
Standard Output
2017-10-07 09:44:11,112 INFO log - jetty-6.1.26.cloudera.4 2017-10-07 09:44:11,131 INFO KMSWebApp - ------------------------------------------------------------- 2017-10-07 09:44:11,131 INFO KMSWebApp - Java runtime version : 1.7.0_121-b00 2017-10-07 09:44:11,131 INFO KMSWebApp - User: slave 2017-10-07 09:44:11,131 INFO KMSWebApp - KMS Hadoop Version: 2.6.0-cdh5.14.0-SNAPSHOT 2017-10-07 09:44:11,131 INFO KMSWebApp - ------------------------------------------------------------- 2017-10-07 09:44:11,134 INFO KMSACLs - 'CREATE' ACL 'CREATE,SET_KEY_MATERIAL' 2017-10-07 09:44:11,134 INFO KMSACLs - 'DELETE' ACL 'DELETE' 2017-10-07 09:44:11,134 INFO KMSACLs - 'ROLLOVER' ACL 'ROLLOVER,SET_KEY_MATERIAL' 2017-10-07 09:44:11,134 INFO KMSACLs - 'GET' ACL 'GET' 2017-10-07 09:44:11,135 INFO KMSACLs - 'GET_KEYS' ACL 'GET_KEYS' 2017-10-07 09:44:11,135 INFO KMSACLs - 'GET_METADATA' ACL 'GET_METADATA' 2017-10-07 09:44:11,135 INFO KMSACLs - 'SET_KEY_MATERIAL' ACL 'SET_KEY_MATERIAL' 2017-10-07 09:44:11,135 INFO KMSACLs - 'GENERATE_EEK' ACL 'GENERATE_EEK' 2017-10-07 09:44:11,135 INFO KMSACLs - 'DECRYPT_EEK' ACL 'DECRYPT_EEK' 2017-10-07 09:44:11,135 INFO KMSACLs - KEY_NAME 'k0' KEY_OP 'ALL' ACL '*' 2017-10-07 09:44:11,135 INFO KMSACLs - KEY_NAME 'k1' KEY_OP 'ALL' ACL '*' 2017-10-07 09:44:11,136 INFO KMSAudit - No audit logger configured, using default. 2017-10-07 09:44:11,137 INFO KMSAudit - Initializing audit logger class org.apache.hadoop.crypto.key.kms.server.SimpleKMSAuditLogger 2017-10-07 09:44:11,137 INFO KMSWebApp - Initialized KeyProvider CachingKeyProvider: jceks://file@/tmp/run_tha_testUYG3Cl/hadoop-common-project/hadoop-kms/target/ddbffdf2-e7d8-4e75-982a-debebb227075/kms.keystore 2017-10-07 09:44:11,138 INFO KMSWebApp - Initialized KeyProviderCryptoExtension EagerKeyGeneratorKeyProviderCryptoExtension: KeyProviderCryptoExtension: CachingKeyProvider: jceks://file@/tmp/run_tha_testUYG3Cl/hadoop-common-project/hadoop-kms/target/ddbffdf2-e7d8-4e75-982a-debebb227075/kms.keystore 2017-10-07 09:44:11,138 INFO KMSWebApp - Default key bitlength is 128 2017-10-07 09:44:11,138 INFO KMSWebApp - KMS Started 2017-10-07 09:44:11,141 INFO PackagesResourceConfig - Scanning for root resource and provider classes in the packages: org.apache.hadoop.crypto.key.kms.server 2017-10-07 09:44:11,146 INFO ScanningResourceConfig - Root resource classes found: class org.apache.hadoop.crypto.key.kms.server.KMS 2017-10-07 09:44:11,146 INFO ScanningResourceConfig - Provider classes found: class org.apache.hadoop.crypto.key.kms.server.KMSJSONWriter class org.apache.hadoop.crypto.key.kms.server.KMSExceptionsProvider class org.apache.hadoop.crypto.key.kms.server.KMSJSONReader 2017-10-07 09:44:11,147 INFO WebApplicationImpl - Initiating Jersey application, version 'Jersey: 1.9 09/02/2011 11:17 AM' 2017-10-07 09:44:11,224 INFO log - Started SocketConnector@localhost:46764 Test KMS running at: http://localhost:46764/kms 2017-10-07 09:44:11,254 INFO kms-audit - UNAUTHORIZED[op=CREATE_KEY, key=k, user=client] 2017-10-07 09:44:11,255 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request POST http://localhost:46764/kms/v1/keys caused exception. 2017-10-07 09:44:11,256 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'CREATE_KEY' on 'k']!! 2017-10-07 09:44:11,256 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,270 INFO kms-audit - UNAUTHORIZED[op=CREATE_KEY, key=k, user=client] 2017-10-07 09:44:11,270 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request POST http://localhost:46764/kms/v1/keys caused exception. 2017-10-07 09:44:11,271 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'CREATE_KEY' on 'k']!! 2017-10-07 09:44:11,271 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,284 INFO kms-audit - UNAUTHORIZED[op=ROLL_NEW_VERSION, key=k, user=client] 2017-10-07 09:44:11,284 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request POST http://localhost:46764/kms/v1/key/k caused exception. 2017-10-07 09:44:11,285 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'ROLL_NEW_VERSION' on 'k']!! 2017-10-07 09:44:11,285 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,300 INFO kms-audit - UNAUTHORIZED[op=ROLL_NEW_VERSION, key=k, user=client] 2017-10-07 09:44:11,300 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request POST http://localhost:46764/kms/v1/key/k caused exception. 2017-10-07 09:44:11,301 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'ROLL_NEW_VERSION' on 'k']!! 2017-10-07 09:44:11,301 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,315 INFO kms-audit - UNAUTHORIZED[op=GET_KEYS, user=client] 2017-10-07 09:44:11,315 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/keys/names caused exception. 2017-10-07 09:44:11,316 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_KEYS']!! 2017-10-07 09:44:11,316 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,330 INFO kms-audit - UNAUTHORIZED[op=GET_KEYS_METADATA, user=client] 2017-10-07 09:44:11,330 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/keys/metadata?key=k caused exception. 2017-10-07 09:44:11,331 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_KEYS_METADATA']!! 2017-10-07 09:44:11,331 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,344 INFO kms-audit - UNAUTHORIZED[op=GET_KEY_VERSION, user=client] 2017-10-07 09:44:11,344 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/keyversion/k%400 caused exception. 2017-10-07 09:44:11,345 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_KEY_VERSION']!! 2017-10-07 09:44:11,345 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,358 INFO kms-audit - UNAUTHORIZED[op=GET_CURRENT_KEY, key=k, user=client] 2017-10-07 09:44:11,358 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k/_currentversion caused exception. 2017-10-07 09:44:11,359 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_CURRENT_KEY' on 'k']!! 2017-10-07 09:44:11,359 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,372 INFO kms-audit - UNAUTHORIZED[op=GET_METADATA, key=k, user=client] 2017-10-07 09:44:11,372 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k/_metadata caused exception. 2017-10-07 09:44:11,373 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_METADATA' on 'k']!! 2017-10-07 09:44:11,373 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,386 INFO kms-audit - UNAUTHORIZED[op=GET_KEY_VERSIONS, key=k, user=client] 2017-10-07 09:44:11,386 WARN KMS - User client@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k/_versions caused exception. 2017-10-07 09:44:11,387 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User:client not allowed to do 'GET_KEY_VERSIONS' on 'k']!! 2017-10-07 09:44:11,387 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:11,407 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k0, user=CREATE] 2017-10-07 09:44:11,409 INFO kms-audit - OK[op=CREATE_KEY, key=k0, user=CREATE] UserProvidedMaterial:false Description:null 2017-10-07 09:44:11,435 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k0, user=DELETE] 2017-10-07 09:44:11,437 INFO kms-audit - OK[op=DELETE_KEY, key=k0, user=DELETE] 2017-10-07 09:44:11,457 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,459 INFO kms-audit - OK[op=CREATE_KEY, key=k1, user=SET_KEY_MATERIAL] UserProvidedMaterial:true Description:null 2017-10-07 09:44:11,482 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,487 INFO kms-audit - OK[op=ROLL_NEW_VERSION, key=k1, user=ROLLOVER] UserProvidedMaterial:false NewVersion:k1@1 2017-10-07 09:44:11,489 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,489 INFO kms-audit - OK[op=INVALIDATE_CACHE, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,491 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,491 WARN KMS - User ROLLOVER@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k1/_eek?num_keys=150&eek_op=generate caused exception. 2017-10-07 09:44:11,506 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,506 INFO kms-audit - OK[op=INVALIDATE_CACHE, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,507 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=ROLLOVER] 2017-10-07 09:44:11,507 WARN KMS - User ROLLOVER@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k1/_eek?num_keys=150&eek_op=generate caused exception. 2017-10-07 09:44:11,530 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,532 INFO kms-audit - OK[op=ROLL_NEW_VERSION, key=k1, user=SET_KEY_MATERIAL] UserProvidedMaterial:true NewVersion:k1@2 2017-10-07 09:44:11,534 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,534 INFO kms-audit - OK[op=INVALIDATE_CACHE, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,535 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,535 WARN KMS - User SET_KEY_MATERIAL@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k1/_eek?num_keys=150&eek_op=generate caused exception. 2017-10-07 09:44:11,550 INFO kms-audit - UNAUTHORIZED[op=MANAGEMENT, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,550 INFO kms-audit - OK[op=INVALIDATE_CACHE, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,551 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=SET_KEY_MATERIAL] 2017-10-07 09:44:11,551 WARN KMS - User SET_KEY_MATERIAL@EXAMPLE.COM (auth:KERBEROS) request GET http://localhost:46764/kms/v1/key/k1/_eek?num_keys=150&eek_op=generate caused exception. 2017-10-07 09:44:11,571 INFO kms-audit - UNAUTHORIZED[op=READ, key=k1, user=GET] 2017-10-07 09:44:11,571 INFO kms-audit - OK[op=GET_KEY_VERSION, key=k1, user=GET, accessCount=1, interval=0ms] 2017-10-07 09:44:11,574 INFO kms-audit - UNAUTHORIZED[op=READ, key=k1, user=GET] 2017-10-07 09:44:11,575 INFO kms-audit - OK[op=GET_CURRENT_KEY, key=k1, user=GET, accessCount=1, interval=0ms] 2017-10-07 09:44:11,598 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,599 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,604 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,605 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,606 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,607 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,608 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,613 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,613 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,614 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,615 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,616 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,616 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,616 INFO kms-audit - OK[op=GENERATE_EEK, key=k1, user=GENERATE_EEK, accessCount=1, interval=0ms] 2017-10-07 09:44:11,645 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,645 INFO kms-audit - OK[op=REENCRYPT_EEK, key=k1, user=GENERATE_EEK, accessCount=1, interval=0ms] 2017-10-07 09:44:11,648 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:11,648 INFO kms-audit - OK[op=REENCRYPT_EEK_BATCH, key=k1, user=GENERATE_EEK] reencrypted 2 keys 2017-10-07 09:44:11,648 INFO KMS - reencryptEncryptedKeys 2 keys for key k1 took 347.5 ?s 2017-10-07 09:44:11,674 INFO kms-audit - UNAUTHORIZED[op=DECRYPT_EEK, key=k1, user=DECRYPT_EEK] 2017-10-07 09:44:11,674 INFO kms-audit - OK[op=DECRYPT_EEK, key=k1, user=DECRYPT_EEK, accessCount=1, interval=0ms] 2017-10-07 09:44:11,698 INFO kms-audit - OK[op=GET_KEYS, user=GET_KEYS] 2017-10-07 09:44:11,721 INFO kms-audit - UNAUTHORIZED[op=READ, key=k1, user=GET_METADATA] 2017-10-07 09:44:11,722 INFO kms-audit - OK[op=GET_METADATA, key=k1, user=GET_METADATA] 2017-10-07 09:44:11,725 INFO kms-audit - UNAUTHORIZED[op=READ, key=k1, user=GET_METADATA] 2017-10-07 09:44:11,725 INFO kms-audit - OK[op=GET_KEYS_METADATA, user=GET_METADATA] 2017-10-07 09:44:12,000 INFO SessionTrackerImpl - SessionTrackerImpl exited loop! 2017-10-07 09:44:12,771 WARN KMS - User CREATE@EXAMPLE.COM (auth:KERBEROS) request POST http://localhost:46764/kms/v1/keys caused exception. 2017-10-07 09:44:12,772 WARN LoadBalancingKMSClientProvider - KMS provider at [http://localhost:46764/kms/v1/] threw an IOException [User [CREATE] is not authorized to create key !!]!! 2017-10-07 09:44:12,772 WARN LoadBalancingKMSClientProvider - Aborting since the Request has failed with all KMS providers in the group. !! 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,795 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,796 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,797 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,802 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,803 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,804 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,805 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,806 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,837 INFO kms-audit - UNAUTHORIZED[op=GENERATE_EEK, key=k1, user=GENERATE_EEK] 2017-10-07 09:44:12,838 INFO log - Stopped SocketConnector@localhost:46764 2017-10-07 09:44:12,840 INFO KMSWebApp - KMS Stopped