2018-12-10 10:58:35,887 DEBUG - [pool-2-thread-5 - edd96bad-c72d-4290-a8b9-8911cbeead3c:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 10:58:35,887 DEBUG - [pool-2-thread-5 - edd96bad-c72d-4290-a8b9-8911cbeead3c:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 10:58:39,500 DEBUG - [pool-2-thread-6 - 81dabdf6-80d9-4834-b7da-b2e5c2f3f1a0:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 10:58:39,501 DEBUG - [pool-2-thread-6 - 81dabdf6-80d9-4834-b7da-b2e5c2f3f1a0:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 10:58:54,842 DEBUG - [pool-2-thread-9:] ~ Knox doFilter /favicon.ico (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:58:54,843 DEBUG - [pool-2-thread-9:] ~ Knox ssoEnabled  true /favicon.ico (AtlasKnoxSSOAuthenticationFilter:163)
2018-12-10 10:58:54,843 DEBUG - [pool-2-thread-9:] ~  constructForwardableURL ==>>null requestURI /favicon.ico (AtlasKnoxSSOAuthenticationFilter:386)
2018-12-10 10:58:54,843 DEBUG - [pool-2-thread-9:] ~  xForwardedURL ==>> null (AtlasKnoxSSOAuthenticationFilter:418)
2018-12-10 10:59:01,301 DEBUG - [pool-2-thread-10 - f6583390-b4e2-4b36-bf70-1bb2ea1618e1:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 10:59:01,301 DEBUG - [pool-2-thread-10 - f6583390-b4e2-4b36-bf70-1bb2ea1618e1:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 10:59:22,316 DEBUG - [pool-2-thread-8:] ~ ==> AtlasLdapAuthenticationProvider getLdapBindAuthentication (AtlasLdapAuthenticationProvider:92)
2018-12-10 10:59:22,332 INFO  - [pool-2-thread-8:] ~  URL 'ldap://sandbox.hortonworks.com:389', root DN is '' (DefaultSpringSecurityContextSource:76)
2018-12-10 10:59:22,691 DEBUG - [pool-2-thread-7:] ~ Knox doFilter /favicon.ico (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:22,692 DEBUG - [pool-2-thread-7:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:22,703 DEBUG - [pool-2-thread-7:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:22,704 DEBUG - [pool-2-thread-7:] ~ Ignoring request /favicon.ico (AtlasAuthorizationFilter:174)
2018-12-10 10:59:23,087 DEBUG - [pool-2-thread-5:] ~ Knox doFilter /favicon.ico (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,088 DEBUG - [pool-2-thread-5:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,089 DEBUG - [pool-2-thread-5:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,089 DEBUG - [pool-2-thread-5:] ~ Ignoring request /favicon.ico (AtlasAuthorizationFilter:174)
2018-12-10 10:59:23,262 DEBUG - [pool-2-thread-6:] ~ Knox doFilter /api/atlas/admin/session (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,262 DEBUG - [pool-2-thread-6:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,262 DEBUG - [pool-2-thread-6:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,263 DEBUG - [pool-2-thread-6:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:23,265 DEBUG - [pool-2-thread-6:] ~ ==> getAtlasResourceType  for /admin/session (AtlasAuthorizationUtils:122)
2018-12-10 10:59:23,265 DEBUG - [pool-2-thread-6:] ~ ==> getApi(/admin/session) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:23,266 DEBUG - [pool-2-thread-6:] ~ <== getApi(admin/session): admin (AtlasAuthorizationUtils:68)
2018-12-10 10:59:23,267 DEBUG - [pool-2-thread-6:] ~ <== Returning AtlasResources [UNKNOWN] for api admin (AtlasAuthorizationUtils:149)
2018-12-10 10:59:23,267 DEBUG - [pool-2-thread-6:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:23,267 DEBUG - [pool-2-thread-9:] ~ Knox doFilter /api/atlas/v2/types/typedefs (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-9:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-6:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-9:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-6:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/admin/session
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-9:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:23,268 DEBUG - [pool-2-thread-6:] ~ Allowing access to unprotected resource types [UNKNOWN] (AtlasAuthorizationFilter:134)
2018-12-10 10:59:23,269 DEBUG - [pool-2-thread-6:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:23,269 DEBUG - [pool-2-thread-9:] ~ ==> getAtlasResourceType  for /v2/types/typedefs (AtlasAuthorizationUtils:122)
2018-12-10 10:59:23,269 DEBUG - [pool-2-thread-9:] ~ ==> getApi(/v2/types/typedefs) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:23,270 DEBUG - [pool-2-thread-9:] ~ <== getApi(v2/types/typedefs): types (AtlasAuthorizationUtils:68)
2018-12-10 10:59:23,270 DEBUG - [pool-2-thread-9:] ~ <== Returning AtlasResources [TYPE] for api types (AtlasAuthorizationUtils:149)
2018-12-10 10:59:23,270 DEBUG - [pool-2-thread-9:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:23,270 DEBUG - [pool-2-thread-9:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,270 DEBUG - [pool-2-thread-6 - d4aace3d-c834-4bd3-a016-edc06e44c17a:] ~ ==> AdminResource.getUserProfile() (AdminResource:242)
2018-12-10 10:59:23,271 DEBUG - [pool-2-thread-9:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/v2/types/typedefs
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:23,271 DEBUG - [pool-2-thread-6 - d4aace3d-c834-4bd3-a016-edc06e44c17a:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,279 DEBUG - [pool-2-thread-8:] ~ Knox doFilter /api/atlas/v2/types/typedefs/headers (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,279 DEBUG - [pool-2-thread-8:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,279 DEBUG - [pool-2-thread-8:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,279 DEBUG - [pool-2-thread-8:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ ==> getAtlasResourceType  for /v2/types/typedefs/headers (AtlasAuthorizationUtils:122)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ ==> getApi(/v2/types/typedefs/headers) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ <== getApi(v2/types/typedefs/headers): types (AtlasAuthorizationUtils:68)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ <== Returning AtlasResources [TYPE] for api types (AtlasAuthorizationUtils:149)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,280 DEBUG - [pool-2-thread-8:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/v2/types/typedefs/headers
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:23,281 DEBUG - [pool-2-thread-9:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149)
2018-12-10 10:59:23,281 DEBUG - [pool-2-thread-9:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:23,281 DEBUG - [pool-2-thread-8:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149)
2018-12-10 10:59:23,282 DEBUG - [pool-2-thread-8:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:23,281 DEBUG - [pool-2-thread-6 - d4aace3d-c834-4bd3-a016-edc06e44c17a:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,285 DEBUG - [pool-2-thread-6 - d4aace3d-c834-4bd3-a016-edc06e44c17a:] ~ <== AdminResource.getUserProfile() (AdminResource:289)
2018-12-10 10:59:23,293 DEBUG - [pool-2-thread-5:] ~ Knox doFilter /api/atlas/v2/types/typedefs (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,293 DEBUG - [pool-2-thread-5:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,294 DEBUG - [pool-2-thread-5:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,294 DEBUG - [pool-2-thread-5:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:23,294 DEBUG - [pool-2-thread-5:] ~ ==> getAtlasResourceType  for /v2/types/typedefs (AtlasAuthorizationUtils:122)
2018-12-10 10:59:23,294 DEBUG - [pool-2-thread-5:] ~ ==> getApi(/v2/types/typedefs) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:23,295 DEBUG - [pool-2-thread-5:] ~ <== getApi(v2/types/typedefs): types (AtlasAuthorizationUtils:68)
2018-12-10 10:59:23,295 DEBUG - [pool-2-thread-5:] ~ <== Returning AtlasResources [TYPE] for api types (AtlasAuthorizationUtils:149)
2018-12-10 10:59:23,295 DEBUG - [pool-2-thread-5:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:23,295 DEBUG - [pool-2-thread-5:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,296 DEBUG - [pool-2-thread-5:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/v2/types/typedefs
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:23,297 DEBUG - [pool-2-thread-5:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149)
2018-12-10 10:59:23,297 DEBUG - [pool-2-thread-5:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:23,298 DEBUG - [pool-2-thread-10:] ~ Knox doFilter /api/atlas/v2/types/typedefs (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:23,298 DEBUG - [pool-2-thread-10:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:23,299 DEBUG - [pool-2-thread-10:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:23,299 DEBUG - [pool-2-thread-10:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:23,299 DEBUG - [pool-2-thread-10:] ~ ==> getAtlasResourceType  for /v2/types/typedefs (AtlasAuthorizationUtils:122)
2018-12-10 10:59:23,299 DEBUG - [pool-2-thread-10:] ~ ==> getApi(/v2/types/typedefs) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:23,300 DEBUG - [pool-2-thread-10:] ~ <== getApi(v2/types/typedefs): types (AtlasAuthorizationUtils:68)
2018-12-10 10:59:23,300 DEBUG - [pool-2-thread-10:] ~ <== Returning AtlasResources [TYPE] for api types (AtlasAuthorizationUtils:149)
2018-12-10 10:59:23,300 DEBUG - [pool-2-thread-10:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:23,300 DEBUG - [pool-2-thread-10:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:23,300 DEBUG - [pool-2-thread-10:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/v2/types/typedefs
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:23,301 DEBUG - [pool-2-thread-10:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149)
2018-12-10 10:59:23,301 DEBUG - [pool-2-thread-10:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:24,028 DEBUG - [pool-2-thread-5:] ~ Knox doFilter /api/atlas/v2/search/saved (AtlasKnoxSSOAuthenticationFilter:148)
2018-12-10 10:59:24,028 DEBUG - [pool-2-thread-5:] ~ Cleaning stale transactions (StaleTransactionCleanupFilter:53)
2018-12-10 10:59:24,028 DEBUG - [pool-2-thread-5:] ~ ==> AuthorizationFilter.doFilter (AtlasAuthorizationFilter:91)
2018-12-10 10:59:24,028 DEBUG - [pool-2-thread-5:] ~ /api/atlas is a valid REST API request!!! (AtlasAuthorizationFilter:102)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ ==> getAtlasResourceType  for /v2/search/saved (AtlasAuthorizationUtils:122)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ ==> getApi(/v2/search/saved) (AtlasAuthorizationUtils:45)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ <== getApi(v2/search/saved): search (AtlasAuthorizationUtils:68)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ <== Returning AtlasResources [ENTITY] for api search (AtlasAuthorizationUtils:149)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ <== AtlasAuthorizationFilter getAtlasAction HTTP Method GET mapped to AtlasAction : READ (AtlasAuthorizationUtils:98)
2018-12-10 10:59:24,029 DEBUG - [pool-2-thread-5:] ~ ==> AtlasAccessRequestImpl-- Initializing AtlasAccessRequest (AtlasAccessRequest:49)
2018-12-10 10:59:24,030 DEBUG - [pool-2-thread-5:] ~ ============================
UserName :: atlas_user
Groups :: [atlas_user, atlas]
URL :: https://sandbox.hortonworks.com:21443/api/atlas/v2/search/saved
Action :: READ
request.getServletPath() :: /api/atlas
============================
 (AtlasAuthorizationFilter:125)
2018-12-10 10:59:24,030 DEBUG - [pool-2-thread-5:] ~ Authorizer result :: true (AtlasAuthorizationFilter:149)
2018-12-10 10:59:24,030 DEBUG - [pool-2-thread-5:] ~ Access is allowed so forwarding the request!!! (AtlasAuthorizationFilter:155)
2018-12-10 10:59:24,046 INFO  - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ GraphTransaction intercept for org.apache.atlas.repository.store.graph.v1.AtlasEntityStoreV1.getByUniqueAttributes (GraphTransactionAdvisor:41)
2018-12-10 10:59:24,059 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ ==> getByUniqueAttribute(__AtlasUserProfile, {name=atlas_user}) (AtlasEntityStoreV1:123)
2018-12-10 10:59:24,061 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ Executing: OrCondition [andExprs=AndExpr [predicates=[HasTerm [propertyName=__typeName, op=EQUAL, value=__AtlasUserProfile], HasTerm [propertyName=__state, op=EQUAL, value=ACTIVE], HasTerm [propertyName=__AtlasUserProfile.name, op=EQUAL, value=atlas_user]]]] (TitanGraphQuery:130)
2018-12-10 10:59:24,170 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ Executing: OrCondition [andExprs=AndExpr [predicates=[HasTerm [propertyName=__superTypeNames, op=EQUAL, value=__AtlasUserProfile], HasTerm [propertyName=__state, op=EQUAL, value=ACTIVE], HasTerm [propertyName=__AtlasUserProfile.name, op=EQUAL, value=atlas_user]]]] (TitanGraphQuery:130)
2018-12-10 10:59:24,194 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Returning HDFS Filesystem Config: Configuration: core-default.xml, core-site.xml, hdfs-default.xml, hdfs-site.xml (HDFSAuditDestination:325)
2018-12-10 10:59:24,206 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Using default ZkCredentialsProvider (SolrZkClient:211)
2018-12-10 10:59:24,212 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Initiating client connection, connectString=sandbox.hortonworks.com:2181/infra-solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.SolrZkClient$3@923fa33 (ZooKeeper:438)
2018-12-10 10:59:24,217 DEBUG - [org.apache.ranger.audit.queue.AuditBatchQueue0-SendThread(sandbox.hortonworks.com:2181):] ~ ==> InMemoryJAASConfiguration.getAppConfigurationEntry(Client) (InMemoryJAASConfiguration:208)
2018-12-10 10:59:24,217 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Waiting for client to connect to ZooKeeper (ConnectionManager:212)
2018-12-10 10:59:24,217 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Checking whether log file exists. hdfPath=hdfs://sandbox.hortonworks.com:8020/ranger/audit/atlas/20181210/atlas_ranger_audit_sandbox.hortonworks.com.log, UGI=hdpsandbox-atlas (auth:SIMPLE) (HDFSAuditDestination:287)
2018-12-10 10:59:24,217 DEBUG - [org.apache.ranger.audit.queue.AuditBatchQueue0-SendThread(sandbox.hortonworks.com:2181):] ~ <== InMemoryJAASConfiguration.getAppConfigurationEntry(Client): {} (InMemoryJAASConfiguration:238)
2018-12-10 10:59:24,217 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0-SendThread(sandbox.hortonworks.com:2181):] ~ Opening socket connection to server sandbox.hortonworks.com/10.0.0.14:2181. Will not attempt to authenticate using SASL (unknown error) (ClientCnxn:1019)
2018-12-10 10:59:24,218 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0-SendThread(sandbox.hortonworks.com:2181):] ~ Socket connection established, initiating session, client: /10.0.0.14:36780, server: sandbox.hortonworks.com/10.0.0.14:2181 (ClientCnxn:864)
2018-12-10 10:59:24,220 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0-SendThread(sandbox.hortonworks.com:2181):] ~ Session establishment complete on server sandbox.hortonworks.com/10.0.0.14:2181, sessionid = 0x1679789a3000053, negotiated timeout = 10000 (ClientCnxn:1279)
2018-12-10 10:59:24,223 INFO  - [zkCallback-2-thread-1:] ~ Watcher org.apache.solr.common.cloud.ConnectionManager@5e27937a name:ZooKeeperConnection Watcher:sandbox.hortonworks.com:2181/infra-solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None (ConnectionManager:104)
2018-12-10 10:59:24,224 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Client is connected to ZooKeeper (ConnectionManager:230)
2018-12-10 10:59:24,224 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Using default ZkACLProvider (SolrZkClient:227)
2018-12-10 10:59:24,226 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Updating cluster state from ZooKeeper...  (ZkStateReader:286)
2018-12-10 10:59:24,296 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ <== AtlasErrorCode.getMessage([__AtlasUserProfile, {name=atlas_user}]) (AtlasErrorCode:139)
2018-12-10 10:59:24,297 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ ==> AtlasErrorCode.getMessage([__AtlasUserProfile, {name=atlas_user}]): Instance __AtlasUserProfile with unique attribute {name=atlas_user} does not exist (AtlasErrorCode:146)
2018-12-10 10:59:24,297 ERROR - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ graph rollback due to exception AtlasBaseException:Instance __AtlasUserProfile with unique attribute {name=atlas_user} does not exist (GraphTransactionInterceptor:73)
2018-12-10 10:59:24,299 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ ==> releaseLockedObjects(): lockedGuids.size: 0 (GraphTransactionInterceptor:187)
2018-12-10 10:59:24,299 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ <== releaseLockedObjects(): lockedGuids.size: 0 (GraphTransactionInterceptor:197)
2018-12-10 10:59:24,300 DEBUG - [pool-2-thread-5 - 647546c8-b7f8-41a7-ab0c-4d29420c42d1:] ~ PERF|DiscoveryREST.getSavedSearches(userName=null)|269 (DiscoveryREST:77)
2018-12-10 10:59:24,343 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Checking whether log file exists. hdfPath=hdfs://sandbox.hortonworks.com:8020/ranger/audit/atlas/20181210/atlas_ranger_audit_sandbox.hortonworks.com.1.log (HDFSAuditDestination:296)
2018-12-10 10:59:24,345 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Log file doesn't exists. Will create and use it. hdfPath=hdfs://sandbox.hortonworks.com:8020/ranger/audit/atlas/20181210/atlas_ranger_audit_sandbox.hortonworks.com.1.log (HDFSAuditDestination:299)
2018-12-10 10:59:24,346 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Creating parent folder for hdfs://sandbox.hortonworks.com:8020/ranger/audit/atlas/20181210/atlas_ranger_audit_sandbox.hortonworks.com.1.log (HDFSAuditDestination:331)
2018-12-10 10:59:24,346 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Creating new log file. hdfPath=hdfs://sandbox.hortonworks.com:8020/ranger/audit/atlas/20181210/atlas_ranger_audit_sandbox.hortonworks.com.1.log (HDFSAuditDestination:305)
2018-12-10 10:59:24,393 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Flushing HDFS audit. Event Size:7 (HDFSAuditDestination:179)
2018-12-10 10:59:39,445 DEBUG - [pool-2-thread-10 - fcd7a089-192e-4cff-bd08-a9ba7f95b785:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 10:59:39,445 DEBUG - [pool-2-thread-10 - fcd7a089-192e-4cff-bd08-a9ba7f95b785:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 11:00:00,184 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue0:] ~ Audit Status Log: name=atlas.async.multi_dest.batch, finalDestination=atlas.async.multi_dest.batch.solr, interval=01:00.002 minutes, events=32, succcessCount=7, totalEvents=32, totalSuccessCount=7 (BaseAuditHandler:310)
2018-12-10 11:00:00,188 INFO  - [org.apache.ranger.audit.queue.AuditBatchQueue1:] ~ Audit Status Log: name=atlas.async.multi_dest.batch, finalDestination=atlas.async.multi_dest.batch.hdfs, interval=01:00.003 minutes, events=32, succcessCount=7, totalEvents=32, totalSuccessCount=7 (BaseAuditHandler:310)
2018-12-10 11:00:09,722 DEBUG - [pool-2-thread-5 - 14113750-5dd4-4e88-9592-f39353657a80:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 11:00:09,722 DEBUG - [pool-2-thread-5 - 14113750-5dd4-4e88-9592-f39353657a80:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 11:00:39,442 DEBUG - [pool-2-thread-6 - fa076694-2011-4f4f-a615-e3340f5ecf20:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 11:00:39,443 DEBUG - [pool-2-thread-6 - fa076694-2011-4f4f-a615-e3340f5ecf20:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 11:01:01,166 DEBUG - [pool-2-thread-7 - e9b87562-c3fb-4b4e-888c-e4b92ba4fb96:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 11:01:01,166 DEBUG - [pool-2-thread-7 - e9b87562-c3fb-4b4e-888c-e4b92ba4fb96:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 11:01:39,411 DEBUG - [pool-2-thread-8 - 388bef3f-2864-49c0-991a-e66b7fbaaf3a:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 11:01:39,411 DEBUG - [pool-2-thread-8 - 388bef3f-2864-49c0-991a-e66b7fbaaf3a:] ~ <== AdminResource.getStatus() (AdminResource:231)
2018-12-10 11:01:55,581 DEBUG - [pool-2-thread-8 - 16ea87da-011a-4dfd-bc1f-b3dbadd4aa4f:] ~ ==> AdminResource.getStatus() (AdminResource:217)
2018-12-10 11:01:55,582 DEBUG - [pool-2-thread-8 - 16ea87da-011a-4dfd-bc1f-b3dbadd4aa4f:] ~ <== AdminResource.getStatus() (AdminResource:231)