hadoop-common-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Xiao Chen (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (HADOOP-14949) TestKMS#testACLs fails intermitently
Date Fri, 13 Oct 2017 23:50:00 GMT

    [ https://issues.apache.org/jira/browse/HADOOP-14949?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16204364#comment-16204364
] 

Xiao Chen commented on HADOOP-14949:
------------------------------------

In the passing runs, the acl reloading is observed:
{noformat}
2017-10-13 16:35:29,055 INFO  KMSACLs - 'CREATE' ACL 'foo'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'DELETE' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'ROLLOVER' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'GET' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'GET_KEYS' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'GET_METADATA' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'SET_KEY_MATERIAL' ACL '*'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'GENERATE_EEK' ACL 'foo'
2017-10-13 16:35:29,055 INFO  KMSACLs - 'DECRYPT_EEK' ACL '*'
{noformat}
This is not seen in the failed runs.

Let's improve the test to not depend on the file mtime and sleep, but be deterministic.

> TestKMS#testACLs fails intermitently
> ------------------------------------
>
>                 Key: HADOOP-14949
>                 URL: https://issues.apache.org/jira/browse/HADOOP-14949
>             Project: Hadoop Common
>          Issue Type: Bug
>          Components: kms, test
>            Reporter: Xiao Chen
>            Assignee: Xiao Chen
>         Attachments: HADOOP-14949.01.patch
>
>
> We have seen some intermittent failures of this test:
> Error Message
> {noformat}
> java.lang.AssertionError
> {noformat}
> Stack Trace
> {noformat}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)
> {noformat}
> Standard Output
> {noformat}
> 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
> {noformat}



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)


---------------------------------------------------------------------
To unsubscribe, e-mail: common-issues-unsubscribe@hadoop.apache.org
For additional commands, e-mail: common-issues-help@hadoop.apache.org


Mime
View raw message