2017-11-10 08:05:57,485 [http-bio-6080-exec-3] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/login.jsp'; against '/login.jsp'
2017-11-10 08:05:57,485 [http-bio-6080-exec-3] DEBUG org.springframework.security.web.FilterChainProxy (FilterChainProxy.java:180) - /login.jsp has an empty filter list
2017-11-10 08:06:03,950 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/login.jsp'
2017-11-10 08:06:03,950 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/styles/**'
2017-11-10 08:06:03,950 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/fonts/**'
2017-11-10 08:06:03,950 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/scripts/**'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/libs/**'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/images/**'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/templates/**'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/service/assets/policylist/*'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/service/assets/resources/grant'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/service/assets/resources/revoke'
2017-11-10 08:06:03,951 [http-bio-6080-exec-4] DEBUG springframework.security.web.util.matcher.AntPathRequestMatcher (AntPathRequestMatcher.java:145) - Checking match of request : '/service/plugins/policies/download/cbsandbox1_hadoop'; against '/service/plugins/policies/download/*'
2017-11-10 08:06:03,952 [http-bio-6080-exec-4] DEBUG org.springframework.security.web.FilterChainProxy (FilterChainProxy.java:180) - /service/plugins/policies/download/cbsandbox1_hadoop?lastKnownVersion=13&lastActivationTime=1510278809705&pluginId=hdfs@cbs-m1.example.com-cbsandbox1_hadoop&clusterName=cbsandbox1 has an empty filter list
2017-11-10 08:06:03,952 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory (AbstractAutowireCapableBeanFactory.java:432) - Creating instance of bean 'serviceREST'
2017-11-10 08:06:03,953 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.RESTErrorUtil org.apache.ranger.rest.ServiceREST.restErrorUtil
2017-11-10 08:06:03,953 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'RESTErrorUtil'
2017-11-10 08:06:03,953 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.ServiceMgr org.apache.ranger.rest.ServiceREST.serviceMgr
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'serviceMgr'
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.AssetMgr org.apache.ranger.rest.ServiceREST.assetMgr
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'assetMgr'
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.XUserMgr org.apache.ranger.rest.ServiceREST.userMgr
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'XUserMgr'
2017-11-10 08:06:03,954 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.ServiceDBStore org.apache.ranger.rest.ServiceREST.svcStore
2017-11-10 08:06:03,955 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'serviceDBStore'
2017-11-10 08:06:03,955 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.ServiceUtil org.apache.ranger.rest.ServiceREST.serviceUtil
2017-11-10 08:06:03,955 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'serviceUtil'
2017-11-10 08:06:03,955 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.service.RangerPolicyService org.apache.ranger.rest.ServiceREST.policyService
2017-11-10 08:06:03,955 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerPolicyService'
2017-11-10 08:06:03,956 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.service.RangerServiceService org.apache.ranger.rest.ServiceREST.svcService
2017-11-10 08:06:03,956 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerServiceService'
2017-11-10 08:06:03,956 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.service.RangerServiceDefService org.apache.ranger.rest.ServiceREST.serviceDefService
2017-11-10 08:06:03,956 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerServiceDefService'
2017-11-10 08:06:03,956 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.service.RangerPluginInfoService org.apache.ranger.rest.ServiceREST.pluginInfoService
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerPluginInfoService'
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.RangerSearchUtil org.apache.ranger.rest.ServiceREST.searchUtil
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerSearchUtil'
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.RangerBizUtil org.apache.ranger.rest.ServiceREST.bizUtil
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerBizUtil'
2017-11-10 08:06:03,957 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.GUIDUtil org.apache.ranger.rest.ServiceREST.guidUtil
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'GUIDUtil'
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.RangerValidatorFactory org.apache.ranger.rest.ServiceREST.validatorFactory
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerValidatorFactory'
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.db.RangerDaoManager org.apache.ranger.rest.ServiceREST.daoManager
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'rangerDaoManager'
2017-11-10 08:06:03,958 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.biz.TagDBStore org.apache.ranger.rest.ServiceREST.tagStore
2017-11-10 08:06:03,959 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'tagDBStore'
2017-11-10 08:06:03,959 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata (InjectionMetadata.java:85) - Processing injected method of bean 'serviceREST': AutowiredFieldElement for org.apache.ranger.common.JSONUtil org.apache.ranger.rest.ServiceREST.jsonUtil
2017-11-10 08:06:03,959 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'JSONUtil'
2017-11-10 08:06:03,959 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'org.springframework.transaction.config.internalTransactionAdvisor'
2017-11-10 08:06:03,959 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'org.springframework.security.methodSecurityMetadataSourceAdvisor'
2017-11-10 08:06:03,960 [http-bio-6080-exec-4] DEBUG org.springframework.aop.framework.autoproxy.AbstractAutoProxyCreator (AbstractAutoProxyCreator.java:538) - Creating implicit proxy for bean 'serviceREST' with 0 common interceptors and 2 specific interceptors
2017-11-10 08:06:03,960 [http-bio-6080-exec-4] DEBUG org.springframework.aop.framework.CglibAopProxy (CglibAopProxy.java:156) - Creating CGLIB proxy: target source is SingletonTargetSource for target object [org.apache.ranger.rest.ServiceREST@d27acd8]
2017-11-10 08:06:03,963 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory (AbstractAutowireCapableBeanFactory.java:460) - Finished creating instance of bean 'serviceREST'
2017-11-10 08:06:03,963 [http-bio-6080-exec-4] DEBUG org.springframework.beans.factory.support.AbstractBeanFactory (AbstractBeanFactory.java:243) - Returning cached instance of singleton bean 'transactionManager'
2017-11-10 08:06:03,964 [http-bio-6080-exec-4] DEBUG org.springframework.transaction.support.AbstractPlatformTransactionManager (AbstractPlatformTransactionManager.java:366) - Creating new transaction with name [org.apache.ranger.rest.ServiceREST.getServicePoliciesIfUpdated]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT; ''
2017-11-10 08:06:03,964 [http-bio-6080-exec-4] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:369) - Opened new EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@91829a4] for JPA transaction
2017-11-10 08:06:03,966 [http-bio-6080-exec-4] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:401) - Exposing JPA transaction as JDBC transaction [SimpleConnectionHandle: com.mchange.v2.c3p0.impl.NewProxyConnection@4d6dbb56]
2017-11-10 08:06:03,966 [http-bio-6080-exec-4] DEBUG org.apache.ranger.rest.ServiceREST (ServiceREST.java:2310) - ==> ServiceREST.getServicePoliciesIfUpdated(cbsandbox1_hadoop, 13, 1510278809705)
2017-11-10 08:06:03,966 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.ServiceDBStore (ServiceDBStore.java:1726) - ==> ServiceDBStore.getServiceByName()
2017-11-10 08:06:03,977 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.ServiceDBStore (ServiceDBStore.java:2359) - ==> ServiceDBStore.getServicePoliciesIfUpdated(cbsandbox1_hadoop, 13)
2017-11-10 08:06:03,982 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.ServiceDBStore (ServiceDBStore.java:2390) - <== ServiceDBStore.getServicePoliciesIfUpdated(cbsandbox1_hadoop, 13): count=0
2017-11-10 08:06:03,982 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.AssetMgr (AssetMgr.java:649) - Not logging HttpServletResponse.SC_NOT_MODIFIED, to enable, update : ranger.log.SC_NOT_MODIFIED
2017-11-10 08:06:03,982 [http-bio-6080-exec-4] DEBUG org.apache.ranger.plugin.util.RangerPerfTracer (RangerPerfTracer.java:101) - [PERF] ServiceREST.getServicePoliciesIfUpdated(serviceName=cbsandbox1_hadoop,lastKnownVersion=13,lastActivationTime=1510278809705): 5
2017-11-10 08:06:03,983 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.AssetMgr (AssetMgr.java:712) - ==> createOrUpdatePluginInfo(pluginInfo=RangerPluginInfo={id={null} createTime={null} updateTime={null} serviceName={cbsandbox1_hadoop} hostName={cbs-m1.example.com} appType={hdfs} ipAddress={10.8.97.13} info={{policyDownloadTime=1510301163983, policyActiveVersion=13, policyActivationTime=1510278809705, policyDownloadedVersion=13}}  }, isPolicyDownloadRequest=true, httpCode=304)
2017-11-10 08:06:03,985 [http-bio-6080-exec-4] DEBUG org.apache.ranger.service.RangerPluginActivityLogger (RangerPluginActivityLogger.java:62) - Using separate thread for committing work [pluginActivityAuditCommitInline:false]
2017-11-10 08:06:03,985 [http-bio-6080-exec-4] DEBUG org.apache.ranger.biz.AssetMgr (AssetMgr.java:760) - <== createOrUpdatePluginInfo(pluginInfo=RangerPluginInfo={id={null} createTime={null} updateTime={null} serviceName={cbsandbox1_hadoop} hostName={cbs-m1.example.com} appType={hdfs} ipAddress={10.8.97.13} info={{policyDownloadTime=1510301163983, policyActiveVersion=13, policyActivationTime=1510278809705, policyDownloadedVersion=13}}  }, isPolicyDownloadRequest=true, httpCode=304)
2017-11-10 08:06:03,986 [http-bio-6080-exec-4] DEBUG org.springframework.transaction.support.AbstractPlatformTransactionManager (AbstractPlatformTransactionManager.java:844) - Initiating transaction rollback
2017-11-10 08:06:03,986 [http-bio-6080-exec-4] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:534) - Rolling back JPA transaction on EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@91829a4]
2017-11-10 08:06:03,988 [http-bio-6080-exec-4] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:594) - Closing JPA EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@91829a4] after transaction
2017-11-10 08:06:03,988 [http-bio-6080-exec-4] DEBUG org.springframework.orm.jpa.EntityManagerFactoryUtils (EntityManagerFactoryUtils.java:338) - Closing JPA EntityManager
2017-11-10 08:06:04,985 [pool-5-thread-1] DEBUG org.springframework.transaction.support.AbstractPlatformTransactionManager (AbstractPlatformTransactionManager.java:366) - Creating new transaction with name [null]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT
2017-11-10 08:06:04,986 [pool-5-thread-1] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:369) - Opened new EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@68b62a50] for JPA transaction
2017-11-10 08:06:04,987 [pool-5-thread-1] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:401) - Exposing JPA transaction as JDBC transaction [SimpleConnectionHandle: com.mchange.v2.c3p0.impl.NewProxyConnection@3bf58863]
2017-11-10 08:06:04,990 [pool-5-thread-1] DEBUG org.apache.ranger.biz.AssetMgr (AssetMgr.java:858) - Updating XXPluginInfo record for service-version
2017-11-10 08:06:04,992 [pool-5-thread-1] DEBUG org.springframework.transaction.support.AbstractPlatformTransactionManager (AbstractPlatformTransactionManager.java:753) - Initiating transaction commit
2017-11-10 08:06:04,992 [pool-5-thread-1] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:508) - Committing JPA transaction on EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@68b62a50]
2017-11-10 08:06:05,001 [pool-5-thread-1] DEBUG org.springframework.orm.jpa.JpaTransactionManager (JpaTransactionManager.java:594) - Closing JPA EntityManager [org.eclipse.persistence.internal.jpa.EntityManagerImpl@68b62a50] after transaction
2017-11-10 08:06:05,001 [pool-5-thread-1] DEBUG org.springframework.orm.jpa.EntityManagerFactoryUtils (EntityManagerFactoryUtils.java:338) - Closing JPA EntityManager
^C