2018-02-16 17:54:44,210 Thread-2 INFO KERNEL - ShutdownHook.run() : ShutdownHook.run : System shutdown in progress... 2018-02-16 17:54:44,262 Thread-2 INFO REGISTRATION - ServerRegistrationServiceImpl.storeServerRegistrationPath() : Registering the server uuid 828dbaa7-c093-4451-883f-5b9bc5e2bf87 with region dc-1 and pod central information 2018-02-16 17:55:14,263 Thread-2 INFO c.a.a.r.s.CustomRuleServiceImpl - CustomRuleServiceImpl.stop() : stop: Stopped rest resource com.apigee.communication.RegistrationInfoImpl@4cabf557 2018-02-16 17:55:14,264 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CustomRulesService 2018-02-16 17:55:14,264 Thread-2 INFO c.a.a.c.a.CustomAggregatesApiService - CustomAggregatesApiService.stop() : Stopped CustomAggregatesApiServiceImpl 2018-02-16 17:55:14,264 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CustomAggregatesApiService 2018-02-16 17:55:14,264 Thread-2 INFO c.a.a.e.s.EntityServiceImpl - EntityServiceImpl.stop() : Stopped EntityServiceImpl 2018-02-16 17:55:14,265 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ax-metadata-service 2018-02-16 17:55:14,265 Thread-2 INFO c.a.a.s.i.AnalyticsSettingsResourceImpl - AnalyticsSettingsServiceImpl.stop() : Stopped listener AnalyticsSettingsRepoServerListener 2018-02-16 17:55:14,265 Thread-2 INFO c.a.a.s.i.AnalyticsSettingsResourceImpl - AnalyticsSettingsServiceImpl.stop() : Stopped rest resource com.apigee.communication.RegistrationInfoImpl@d96ff6c 2018-02-16 17:55:14,265 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AnalyticsSettingsService 2018-02-16 17:55:14,265 Thread-2 INFO c.a.a.r.AnalyticsReportingService - AnalyticsReportingService.stop() : Stopped AnalyticsReportingApiService 2018-02-16 17:55:14,265 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AnalyticsReportingApiService 2018-02-16 17:55:14,266 Thread-2 INFO c.a.a.r.i.CustomAXReportServiceImpl - CustomAXReportServiceImpl.stop() : Stopped rest resource com.apigee.communication.RegistrationInfoImpl@3cd11315 2018-02-16 17:55:14,266 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CustomReportApiService 2018-02-16 17:55:14,266 Thread-2 INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.stop() : Stopping service of Type : DataApiServiceImpl 2018-02-16 17:55:14,266 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : DataApiService 2018-02-16 17:55:14,266 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : DataAccessService 2018-02-16 17:55:14,269 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : QueryService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : JSONSubTypeRegisterService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : SecureStoreService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : RebuildIndexService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : NodeScriptService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CpsKeyValueMapService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : LegacyKeyValueMapService 2018-02-16 17:55:14,270 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : KeyValueMapService 2018-02-16 17:55:14,271 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : OAuth2ManagementService 2018-02-16 17:55:14,271 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : OAuth1ManagementService 2018-02-16 17:55:14,271 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AppService 2018-02-16 17:55:14,271 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ApiProductService 2018-02-16 17:55:14,271 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : DeveloperService 2018-02-16 17:55:14,274 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : KMSOrganizationService 2018-02-16 17:55:14,274 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : KeyManagementService 2018-02-16 17:55:14,274 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : DeveloperAppService 2018-02-16 17:55:14,274 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CompanyService 2018-02-16 17:55:14,275 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ConfigurationService 2018-02-16 17:55:14,275 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ApplicationDistributionService 2018-02-16 17:55:14,275 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : SecurityService 2018-02-16 17:55:14,275 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ServerMetricsService 2018-02-16 17:55:14,276 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : LoggingService 2018-02-16 17:55:14,284 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ResourceService 2018-02-16 17:55:14,288 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : UserService 2018-02-16 17:55:14,291 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AuthenticationService 2018-02-16 17:55:14,292 Thread-2 INFO IDENTITY_ZONE - DataStoreFactory.shutDown() : DataStoreFactory.shutDown() : DataStoreFactory shutdown completed 2018-02-16 17:55:14,292 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : IdentityZoneService 2018-02-16 17:55:14,292 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AccessControl 2018-02-16 17:55:14,295 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CacheService 2018-02-16 17:55:14,296 Thread-2 INFO CLUSTER - ServerState.setState() : State of f8f20707-e02f-492a-bf3d-ec9d88e8a0ea is now CLOSED. handle = 10.105.3.137 at 1518771314296 2018-02-16 17:55:14,297 Thread-2 INFO CLUSTER - ServerState.setState() : State of 8cef7a4f-edc6-4345-b79c-57bc9d8d259d is now CLOSED. handle = 10.105.3.137 at 1518771314297 2018-02-16 17:55:14,298 Thread-2 INFO CLUSTER - ServerState.setState() : State of 5b9dbfe0-6bda-40fe-8242-14f2b0b2ecf3 is now CLOSED. handle = 10.105.3.137 at 1518771314298 2018-02-16 17:55:14,299 Thread-2 INFO CLUSTER - ServerState.setState() : State of efe0149c-a0ec-4ae8-807e-e2418b4b73c4 is now CLOSED. handle = 10.105.3.137 at 1518771314299 2018-02-16 17:55:14,300 Thread-2 INFO CLUSTER - ServerState.setState() : State of 406bb057-25eb-47a4-a724-eaacfd575640 is now CLOSED. handle = 10.105.3.137 at 1518771314300 2018-02-16 17:55:14,300 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ClusterService 2018-02-16 17:55:14,300 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ServerBindObserverService 2018-02-16 17:55:14,301 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CpsManagementService 2018-02-16 17:55:14,302 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ServerBindService 2018-02-16 17:55:14,303 Thread-2 WARN KERNEL.DEPLOYMENT - ServiceDeployer.loadPropertiesFromResources() : ServiceDeployer.getProperties : Resource file notification.properties not found in classpath 2018-02-16 17:55:14,303 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : EdgeNotificationService 2018-02-16 17:55:14,304 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : SchedulerService 2018-02-16 17:55:14,305 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AuditQueryService 2018-02-16 17:55:14,305 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AuditService 2018-02-16 17:55:14,305 Thread-2 INFO APIMODEL.COMMON - ApiModelRepositoryServiceImpl.stop() : Stopping API Modelling service... 2018-02-16 17:55:14,305 Thread-2 INFO APIMODEL.COMMON - ApiModelDataStore.shutDown() : ApiModelDataStore.shutDown() : Shutting down cassandra audit datastore 2018-02-16 17:55:14,305 Thread-2 INFO APIMODEL.COMMON - DataStoreFactory.shutDown() : DataStoreFactory.shutDown() : DataStoreFactory shutdown completed 2018-02-16 17:55:14,305 Thread-2 INFO APIMODEL.COMMON - ApiModelRepositoryServiceImpl.stop() : Stopped API Modelling service successfully. 2018-02-16 17:55:14,305 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ApiModelRepositoryService 2018-02-16 17:55:14,306 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : MetricsService 2018-02-16 17:55:14,307 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ServerRegistrationService 2018-02-16 17:55:14,308 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : RepositoryService 2018-02-16 17:55:14,341 Thread-2 INFO SERVICE.LICENSE - LicensingServiceImpl.stop() : Stopping licensing service 2018-02-16 17:55:14,341 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : LicensingService 2018-02-16 17:55:14,341 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : FeatureFlagService 2018-02-16 17:55:14,351 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : ZooKeeperService 2018-02-16 17:55:14,355 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : SystemInfoService 2018-02-16 17:55:14,355 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : OrganizationService 2018-02-16 17:55:14,355 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : LogService 2018-02-16 17:55:14,355 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : AlertService 2018-02-16 17:55:14,357 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : EventService 2018-02-16 17:55:14,357 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : DebugService 2018-02-16 17:55:15,362 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : CommunicationService 2018-02-16 17:55:15,380 Thread-2 INFO o.e.j.s.h.ContextHandler - ContextHandler.doStop() : stopped o.e.j.w.WebAppContext{/,file:/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api/},/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api 2018-02-16 17:55:15,436 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : WebService 2018-02-16 17:55:15,436 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : MonitoringService 2018-02-16 17:55:15,437 Thread-2 INFO KERNEL.DEPLOYMENT - ServiceDeployer.stopService() : ServiceDeployer.unDeploy() : Successfully stopped the service : MachineKeyService 2018-02-16 17:55:15,438 WebServer INFO o.e.j.u.t.ShutdownThread - ShutdownThread.hook() : shutdown already commenced 2018-02-16 18:00:33,091 main INFO KERNEL - MicroKernel.initInternals() : MicroKernel.start() : MicroKernel start initiated... 2018-02-16 18:00:33,095 main INFO KERNEL - MicroKernel.logJavaAndOSProperties() : Java/OS Properties - {java.ext.dirs=/usr/java/jdk1.8.0_131/jre/lib/ext:/usr/java/packages/lib/ext, java.runtime.name=Java(TM) SE Runtime Environment, java.runtime.version=1.8.0_131-b11, java.specification.version=1.8, java.vendor=Oracle Corporation, java.security.nssprovider.enabled=false, java.class.version=52.0, java.vendor.url=http://java.oracle.com/, java.specification.name=Java Platform API Specification, java.security.auth.login.config=/opt/apigee/edge-management-server-4.17.01-0.0.1414/conf/jaas.config, java.awt.printerjob=sun.print.PSPrinterJob, os.arch=amd64, java.vm.version=25.131-b11, os.name=Linux, java.home=/usr/java/jdk1.8.0_131/jre, os.version=3.10.0-514.21.2.el7.x86_64, java.endorsed.dirs=/usr/java/jdk1.8.0_131/jre/lib/endorsed, java.version=1.8.0_131, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.vm.specification.version=1.8, java.vendor.url.bug=http://bugreport.sun.com/bugreport/, java.vm.specification.name=Java Virtual Machine Specification, java.specification.vendor=Oracle Corporation, java.vm.vendor=Oracle Corporation, java.vm.info=mixed mode, java.security.properties=../conf/jvmsecurity.properties, java.library.path=/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, java.vm.specification.vendor=Oracle Corporation, java.io.tmpdir=/var/tmp} 2018-02-16 18:00:33,138 main INFO KERNEL.CONFIG - KernelConfiguration.loadKernelProperties() : KernelConfiguration.loadKernelProperties() : MicroKernel properties : {checkmarx.scan.validator.enabled=true, debugsession.timeout=120, profile=management-server, java.security.properties=../conf/jvmsecurity.properties, datastax.loadTokenToHostMappingForKeyspaces=true, jsse.enableSNIExtension=false, jute.maxbuffer=10485750, java.io.tmpdir=/var/tmp, has.monetization=${microkernel_hasMonetization}, qpid.session.command_limit=524288, bundle.validation.schema.enabled=true, com.warrenstrange.googleauth.rng.algorithm=SHA1PRNG, default.response.format=json, advisory.logging.enabled=false, rbac.number.of.tries.for.email.search=1, jdk.tls.allowUnsafeServerCertChange=true, astyanax.datastores=taurus-datastore,kms-datastore,dc-datastore,cache-datastore,counter-datastore,keyvaluemap-datastore,application-datastore,audit-datastore,apimodel-datastore,auth-datastore,scheduler-datastore,edgenotification-datastore, javax.xml.xpath.XPathFactory=http://java.sun.com/jaxp/xpath/dom=org.apache.xpath.jaxp.XPathFactoryImpl, migration.mode.status=false, bean.retry.enabled=true, useG1GC=false, javax.xml.transform.TransformerFactory=net.sf.saxon.TransformerFactoryImpl, useStringDeduplication=false, qpid.session.byte_limit=8388608, lib.dir.name=lib/infra/services,lib/gateway/services,lib/gateway/steps,lib/analytics/services, sun.net.maxDatagramSockets=2048, pod=central, com.warrenstrange.googleauth.rng.algorithmProvider=SUN, validation.targetendpoint.connection.check.enabled=true, casssandra.maxConnectTimeInMillis=-1, setCodeCache=false, json.request.schema.validation.enabled=true, license.file.location=/opt/apigee/customer/conf/license.txt, log.level=INFO, validation.entity.name.enabled=true, shutdownServicesOnStop=true, region=dc-1, python.verbose=error, bundle.validation.enabled=false, authenticated.user.header.name=X-Apigee-Current-User, javacallout.policy.validate=false, java.security.nssprovider.enabled=false} 2018-02-16 18:00:33,808 main ERROR KERNEL - MicroKernel.scanDeployments() : MicroKernel.scanDeployments() : Some modules and services were present in the profile but not in the class path 2018-02-16 18:00:33,808 main ERROR KERNEL - MicroKernel.scanDeployments() : Missing modules: [gateway-metrics] 2018-02-16 18:00:33,808 main ERROR KERNEL - MicroKernel.scanDeployments() : Missing services: [CumulativeMetricsService] 2018-02-16 18:00:33,808 main ERROR KERNEL - MicroKernel.scanDeployments() : Not exiting this time but continuing anyway. 2018-02-16 18:00:34,033 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : MachineKeyService that implements InitializeAwareService 2018-02-16 18:00:34,037 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : MachineKeyService 2018-02-16 18:00:34,052 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : WebService that implements InitializeAwareService 2018-02-16 18:00:34,082 main INFO c.a.r.f.c.Container - Container.getConnectors() : Container.getConnectors() - Serving http on port - 8080 2018-02-16 18:00:34,268 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : WebService 2018-02-16 18:00:34,268 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : CommunicationService that implements InitializeAwareService 2018-02-16 18:00:34,278 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : CommunicationService 2018-02-16 18:00:34,283 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : EventService that implements InitializeAwareService 2018-02-16 18:00:34,290 main INFO FILE_LOGGER - BufferedWriter.start() : Starting logging thread 2018-02-16 18:00:34,294 main INFO FILE_LOGGER - SizeBasedFileRotator.() : Creating sizeBased rotator with maxSize 104857600 2018-02-16 18:00:34,294 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : EventService 2018-02-16 18:00:34,305 Thread-1 INFO FILE_LOGGER - BufferedWriter.run() : Signalling threadInit completion 2018-02-16 18:00:34,325 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : ZooKeeperService that implements InitializeAwareService 2018-02-16 18:00:37,874 main INFO o.a.c.f.i.CuratorFrameworkImpl - CuratorFrameworkImpl.start() : Starting 2018-02-16 18:00:37,909 main INFO ZOOKEEPER - ZooKeeperServiceImpl.start() : Use binocular pathwatcher service: false 2018-02-16 18:00:37,909 main INFO ZOOKEEPER - ZooKeeperServiceImpl.start() : Use curator based pathwatcher service: false 2018-02-16 18:00:37,910 main INFO c.a.e.ValidationService - ValidationService.register() : Registered validator for component [zookeeper] 2018-02-16 18:00:37,910 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : ZooKeeperService 2018-02-16 18:00:37,913 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : RepositoryService that implements InitializeAwareService 2018-02-16 18:00:37,924 main-EventThread ERROR o.a.c.ConnectionState - ConnectionState.checkState() : Authentication failed 2018-02-16 18:00:37,958 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:37,959 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:38,012 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : RepositoryService 2018-02-16 18:00:38,012 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : ServerRegistrationService that implements InitializeAwareService 2018-02-16 18:00:38,048 main-EventThread INFO o.a.c.f.s.ConnectionStateManager - ConnectionStateManager.postState() : State change: CONNECTED 2018-02-16 18:00:38,065 main INFO REGISTRATION - ServerRegistrationServiceImpl.initResolutionScopes() : server types [scheduler-datastore, audit-datastore, mint-rdbms-datastore, kms-datastore, dc-datastore, application-datastore, postgres-server, counter-datastore, user-settings-datastore, analytics-datastore, router, endpoint, consumer-server, aries-processor, ldap, message-processor, edgenotification-datastore, dw-server, ingest-server, qpid-server, aries-datastore, cache-datastore, zookeeper, apimodel-datastore, taurus-datastore, keyvaluemap-datastore, notification-datastore, mint-datastore, ax-hadoop-ingester, management-server, identityzone-datastore, reportcrud-datastore, auth-datastore] 2018-02-16 18:00:38,072 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : ServerRegistrationService 2018-02-16 18:00:38,086 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Initializing service : ServerBindService that implements InitializeAwareService 2018-02-16 18:00:38,088 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.init() : ServiceDeployer.init() : Successfully initialized the service : ServerBindService 2018-02-16 18:00:38,116 main INFO K.DATASTORE.NOSQL - EntityCache.() : Default KMS entity cache TTL set to 3 2018-02-16 18:00:38,416 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : MachineKeyService 2018-02-16 18:00:38,417 main INFO FILE_LOGGER - BufferedWriter.start() : Starting logging thread 2018-02-16 18:00:38,417 main INFO FILE_LOGGER - SizeBasedFileRotator.() : Creating sizeBased rotator with maxSize 134217728 2018-02-16 18:00:38,419 Thread-3 INFO FILE_LOGGER - BufferedWriter.run() : Signalling threadInit completion 2018-02-16 18:00:38,420 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : MonitoringService 2018-02-16 18:00:38,422 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : WebService 2018-02-16 18:00:38,430 WebServer INFO o.e.jetty.server.Server - Server.doStart() : jetty-8.0.4.v20111024 2018-02-16 18:00:38,560 WebServer INFO o.e.j.w.StandardDescriptorProcessor - StandardDescriptorProcessor.visitServlet() : NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet 2018-02-16 18:00:38,616 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CommunicationService 2018-02-16 18:00:38,620 WebServer INFO o.e.j.s.h.ContextHandler - ContextHandler.callContextInitialized() : started o.e.j.w.WebAppContext{/,file:/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api/},/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api 2018-02-16 18:00:38,620 WebServer INFO o.e.j.s.h.ContextHandler - ContextHandler.callContextInitialized() : started o.e.j.w.WebAppContext{/,file:/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api/},/opt/apigee/edge-management-server-4.17.01-0.0.1414/webapps/api 2018-02-16 18:00:38,630 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : DebugService 2018-02-16 18:00:38,630 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : EventService 2018-02-16 18:00:38,631 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AlertService 2018-02-16 18:00:38,637 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : LogService 2018-02-16 18:00:38,980 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : OrganizationService 2018-02-16 18:00:38,980 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : SystemInfoService 2018-02-16 18:00:38,980 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ZooKeeperService 2018-02-16 18:00:38,987 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /featureflag/{featureName}/global/on/{time}, async is false 2018-02-16 18:00:38,999 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /featureflag/{featureName}/global/off/{time}, async is false 2018-02-16 18:00:39,000 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /featureflag/{featureName}/{subType}/{name}/on/{time}, async is false 2018-02-16 18:00:39,001 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /featureflag/{featureName}/{subType}/{name}/off/{time}, async is false 2018-02-16 18:00:39,002 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : FeatureFlagService 2018-02-16 18:00:39,221 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : LicensingService 2018-02-16 18:00:39,233 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : RepositoryService 2018-02-16 18:00:39,294 main INFO REGISTRATION - ServerRegistrationServiceImpl.initResolutionScopes() : server types [scheduler-datastore, audit-datastore, mint-rdbms-datastore, kms-datastore, dc-datastore, application-datastore, postgres-server, counter-datastore, user-settings-datastore, analytics-datastore, router, endpoint, consumer-server, aries-processor, ldap, message-processor, edgenotification-datastore, dw-server, ingest-server, qpid-server, aries-datastore, cache-datastore, zookeeper, apimodel-datastore, taurus-datastore, keyvaluemap-datastore, notification-datastore, mint-datastore, ax-hadoop-ingester, management-server, identityzone-datastore, reportcrud-datastore, auth-datastore] 2018-02-16 18:00:39,389 main WARN REGISTRATION - CommunicationInfoBuilder.loadIPAddresses() : CommunicationInfoBuilder.loadIPAddresses : File /opt/apigee/ec2.properties is missing. Will use localhost as default for all the hosts 2018-02-16 18:00:39,618 main INFO REGISTRATION - ServerRegistrationServiceImpl.storeServerRegistrationPath() : Registering the server uuid 828dbaa7-c093-4451-883f-5b9bc5e2bf87 with region dc-1 and pod central information 2018-02-16 18:00:39,658 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ServerRegistrationService 2018-02-16 18:00:39,758 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : MetricsService 2018-02-16 18:00:39,758 main INFO APIMODEL.COMMON - ApiModelRepositoryServiceImpl.start() : Starting API Modelling service... 2018-02-16 18:00:39,791 main INFO APIMODEL.COMMON - DataStoreFactory.init() : In DataStoreFactory.init() properties : {authurl=https://api.enterprise.apigee.com/v1/users/{user}/authenticate, cassandra.defaults.thrift.socketTimeoutInMillis=10000, records.limit.per.apicall=500, authschemes.v2.migration.enabled=true, authschemes.v1.disabled=false, datastoretype=apimodel-datastore, testapi.proxy=https://apiconsole-prod.apigee.net/smartdocs/v1/sendrequest, read.consistencylevel=LOCAL_QUORUM, write.consistencylevel=LOCAL_QUORUM, keyspace=apimodel, endpoint=https://api.enterprise.apigee.com, authschemes.v2.enabled=true} 2018-02-16 18:00:39,792 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:39,793 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:39,798 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/apimodel-datastore/{uuid}, async is true 2018-02-16 18:00:39,803 main INFO APIMODEL.COMMON - ApiModelDataStore.init() : ApiModelDataStore.init(servers) : Initializing call for cassandra audit datastore with server beans 2018-02-16 18:00:39,804 main INFO APIMODEL.COMMON - ApiModelDataStore.initializeDataStoreClient() : ApiModelDataStore.initializeDataStoreClient() : Initializing data stores 2018-02-16 18:00:39,804 main INFO APIMODEL.COMMON - ApiModelDataStore.initializeDataStoreClient() : ApiModelDataStore.initializeDataStoreClient() : No datastores configured 2018-02-16 18:00:39,804 main INFO APIMODEL.COMMON - ApiModelDataStore.init() : ApiModelDataStore.init() : Initializing cassandra api model datastore 2018-02-16 18:00:39,804 main INFO APIMODEL.COMMON - DataStoreFactory.init() : DataStoreFactory.init() : DataStoreFactory initialized 2018-02-16 18:00:39,815 main INFO APIMODEL.COMMON - ApiModelRepositoryServiceImpl.start() : Started API Modelling service successfully 2018-02-16 18:00:39,815 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ApiModelRepositoryService 2018-02-16 18:00:39,818 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: ONE. 2018-02-16 18:00:39,818 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:39,821 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/audit-datastore/{uuid}, async is true 2018-02-16 18:00:39,825 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AuditService 2018-02-16 18:00:39,828 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AuditQueryService 2018-02-16 18:00:39,844 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/scheduler-datastore/{uuid}, async is true 2018-02-16 18:00:39,849 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:39,849 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:39,918 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : SchedulerService 2018-02-16 18:00:39,920 main WARN KERNEL.DEPLOYMENT - ServiceDeployer.loadPropertiesFromResources() : ServiceDeployer.getProperties : Resource file notification.properties not found in classpath 2018-02-16 18:00:39,927 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/edgenotification-datastore/{uuid}, async is true 2018-02-16 18:00:39,932 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:39,932 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: LOCAL_QUORUM. 2018-02-16 18:00:39,932 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : EdgeNotificationService 2018-02-16 18:00:39,963 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ServerBindService 2018-02-16 18:00:39,966 main INFO CpsLibrary - CpsPropertiesUtil.() : Cps enabled flag is false 2018-02-16 18:00:39,966 main INFO CPS_MANAGEMENT - CpsManagementServiceImpl.start() : Starting CpsManagementService, Properties:{kms.cache.enabled=false, kms.read.nonlocalconsistencylevel=QUORUM, kms.maxactive_cassandra_connections=8, quota.cassandra.defaults.connectTimeoutInMillis=10000, kvm.write.consistencylevel=LOCAL_QUORUM, kvm.read.nonlocalconsistencylevel=QUORUM, kvm.localdc.enabled=true, quota.read.nonlocalconsistencylevel=QUORUM, quota.enabled=false, kms.localdc.enabled=true, quota.cache.enabled=false, kvm.maxactive_cassandra_connections=8, kvm.cache.enabled=false, quota.localdc.enabled=true, cassandra.hipaa-ring01.dml_password=NULL, kms.cassandra.defaults.socketReadTimeoutInMillis=10000, quota.maxactive_cassandra_connections=8, kvm.cassandra.defaults.connectTimeoutInMillis=10000, kms.write.consistencylevel=LOCAL_QUORUM, kms.read.localconsistencylevel=LOCAL_QUORUM, quota.read.localconsistencylevel=LOCAL_QUORUM, quota.perses.enabled=true, kvm.perses.enabled=true, cassandra.hipaa-ring01.ddl_password=NULL, kvm.read.localconsistencylevel=LOCAL_QUORUM, cassandra.hipaa-ring01.dml_user=NULL, kms.app_names_fetch_limit_for_developers_listing=100, kvm.cassandra.defaults.socketReadTimeoutInMillis=10000, cassandra.hipaa-ring01.ddl_user=NULL, quota.write.consistencylevel=LOCAL_QUORUM, quota.cassandra.defaults.socketReadTimeoutInMillis=10000, kms.cassandra.defaults.connectTimeoutInMillis=10000, kms.perses.enabled=true} 2018-02-16 18:00:39,971 main INFO o.a.c.f.i.CuratorFrameworkImpl - CuratorFrameworkImpl.start() : Starting 2018-02-16 18:00:39,980 main INFO i.a.t.r.RepositoryFactory - RepositoryFactory.get() : RepositoryFactory: [class io.apigee.tenant.repository.impl.zk.TenantRepositoryImpl] 2018-02-16 18:00:39,981 main-EventThread ERROR o.a.c.ConnectionState - ConnectionState.checkState() : Authentication failed 2018-02-16 18:00:39,985 main-EventThread INFO o.a.c.f.s.ConnectionStateManager - ConnectionStateManager.postState() : State change: CONNECTED 2018-02-16 18:00:39,986 main INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.ddl_password in properties 2018-02-16 18:00:39,986 main INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.ddl_user in properties 2018-02-16 18:00:39,992 main INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_password in properties 2018-02-16 18:00:39,992 main INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_user in properties 2018-02-16 18:00:39,992 main INFO i.a.t.r.RepositoryFactory - RepositoryFactory.get() : RepositoryFactory: [class io.apigee.tenant.repository.impl.zk.TenantRepositoryImpl] 2018-02-16 18:00:39,995 main INFO ZOOKEEPER.LIBRARY - CuratorBasedPathWatcherService.initWatchingInfra() : Enable Curator In Async mode: true 2018-02-16 18:00:40,010 main INFO ZOOKEEPER.LIBRARY - CuratorBasedPathWatcherService.initWatchingInfra() : Enable Curator In Async mode: true 2018-02-16 18:00:40,012 main INFO ZOOKEEPER.LIBRARY - CuratorBasedPathWatcherService.initWatchingInfra() : Enable Curator In Async mode: true 2018-02-16 18:00:40,020 Apigee-Main-1 INFO CPS_MANAGEMENT - CpsManagementServiceImpl$3.run() : Initializing KvmServiceFactoryFacade 2018-02-16 18:00:40,026 Apigee-Main-2 INFO CPS_MANAGEMENT - CpsManagementServiceImpl$2.run() : Initializing KmsServiceFactoryFacade 2018-02-16 18:00:40,027 Apigee-Main-2 INFO CpsLibrary - CpsServiceFactoryFacade.getCpsServiceFactoryFacade() : ConnectionPool configuration options for serviceType[KMS] and namespace[kms.] is {LOCAL_CONSISTENCY_LEVEL=LOCAL_QUORUM, LOCAL_DC=dc-1, SOCKET_CONNECTION_TIMEOUT_MILLIS=10000, SOCKET_READ_TIMEOUT_MILLIS=10000, NON_LOCAL_CONSISTENCY_LEVEL=QUORUM, MAX_CONNECTIONS_PER_HOST=8} 2018-02-16 18:00:40,027 Apigee-Main-2 INFO CpsLibrary - CpsServiceFactoryFacade.getCpsServiceFactoryFacade() : ServiceFactory options for serviceType[KMS] and namespace[kms.] is {ENABLE_PERSES=true, ENABLE_LOCAL_CACHE=false} 2018-02-16 18:00:40,028 Apigee-Main-1 INFO CpsLibrary - CpsServiceFactoryFacade.getCpsServiceFactoryFacade() : ConnectionPool configuration options for serviceType[KVM] and namespace[kvm.] is {LOCAL_CONSISTENCY_LEVEL=LOCAL_QUORUM, LOCAL_DC=dc-1, SOCKET_CONNECTION_TIMEOUT_MILLIS=10000, SOCKET_READ_TIMEOUT_MILLIS=10000, NON_LOCAL_CONSISTENCY_LEVEL=QUORUM, MAX_CONNECTIONS_PER_HOST=8} 2018-02-16 18:00:40,028 Apigee-Main-1 INFO CpsLibrary - CpsServiceFactoryFacade.getCpsServiceFactoryFacade() : ServiceFactory options for serviceType[KVM] and namespace[kvm.] is {ENABLE_PERSES=true, ENABLE_LOCAL_CACHE=false} 2018-02-16 18:00:40,028 Apigee-Main-1 INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_password in properties 2018-02-16 18:00:40,029 Apigee-Main-1 INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_user in properties 2018-02-16 18:00:40,028 Apigee-Main-2 INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_password in properties 2018-02-16 18:00:40,030 Apigee-Main-2 INFO CpsLibrary - AbstractCredentialsStore.getCredentialsMap() : Value is empty for property:cassandra.hipaa-ring01.dml_user in properties 2018-02-16 18:00:40,034 Apigee-Main-1 INFO CPS_MANAGEMENT - CpsManagementServiceImpl.getAllRings() : No rings configured; skipping initialization of kvmServiceFactory 2018-02-16 18:00:40,035 Apigee-Main-2 INFO CPS_MANAGEMENT - CpsManagementServiceImpl.getAllRings() : No rings configured; skipping initialization of kvmServiceFactory 2018-02-16 18:00:40,035 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CpsManagementService 2018-02-16 18:00:40,036 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ServerBindObserverService 2018-02-16 18:00:40,038 main INFO CLUSTER - ClusterServiceImpl.start() : Registering to join a cluster with servers of type management-server 2018-02-16 18:00:40,041 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/management-server/{uuid}, async is true 2018-02-16 18:00:40,222 Timer-0 INFO SERVICE.LICENSE - ScheduledLicenseValidator.run() : License found and is valid !!! 2018-02-16 18:00:40,276 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/bindings/servers/{uuid}/organizations/{org}/environments/{env}, async is true 2018-02-16 18:00:40,281 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/bindings/orgs/{org}, async is true 2018-02-16 18:00:40,284 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/bindings/orgenvs/{org}/environments/{env}, async is true 2018-02-16 18:00:40,297 main INFO SERVER.BINDING - ServerBindServiceImpl.getBindings() : Could not fetch bindings from cache for uuid f8f20707-e02f-492a-bf3d-ec9d88e8a0ea. Fetching from repo and adding to cache 2018-02-16 18:00:40,328 main INFO SERVER.BINDING - ServerBindServiceImpl.getBindings() : Could not fetch bindings from cache for uuid 406bb057-25eb-47a4-a724-eaacfd575640. Fetching from repo and adding to cache 2018-02-16 18:00:40,348 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of f8f20707-e02f-492a-bf3d-ec9d88e8a0ea is now HB_FAILED. handle = 10.105.3.137 at 1518771640348 2018-02-16 18:00:40,349 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 406bb057-25eb-47a4-a724-eaacfd575640 is now HB_FAILED. handle = 10.105.3.137 at 1518771640349 2018-02-16 18:00:40,359 main INFO SERVER.BINDING - ServerBindServiceImpl.getBindings() : Could not fetch bindings from cache for uuid 5b9dbfe0-6bda-40fe-8242-14f2b0b2ecf3. Fetching from repo and adding to cache 2018-02-16 18:00:40,371 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 5b9dbfe0-6bda-40fe-8242-14f2b0b2ecf3 is now HB_FAILED. handle = 10.105.3.137 at 1518771640371 2018-02-16 18:00:40,392 main INFO SERVER.BINDING - ServerBindServiceImpl.getBindings() : Could not fetch bindings from cache for uuid efe0149c-a0ec-4ae8-807e-e2418b4b73c4. Fetching from repo and adding to cache 2018-02-16 18:00:40,415 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 5b9dbfe0-6bda-40fe-8242-14f2b0b2ecf3 is now STARTING. handle = 10.105.3.137 at 1518771640415 2018-02-16 18:00:40,416 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of efe0149c-a0ec-4ae8-807e-e2418b4b73c4 is now HB_FAILED. handle = 10.105.3.137 at 1518771640416 2018-02-16 18:00:40,417 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of f8f20707-e02f-492a-bf3d-ec9d88e8a0ea is now STARTING. handle = 10.105.3.137 at 1518771640417 2018-02-16 18:00:40,418 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 406bb057-25eb-47a4-a724-eaacfd575640 is now STARTING. handle = 10.105.3.137 at 1518771640418 2018-02-16 18:00:40,421 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of efe0149c-a0ec-4ae8-807e-e2418b4b73c4 is now STARTING. handle = 10.105.3.137 at 1518771640421 2018-02-16 18:00:40,425 main INFO SERVER.BINDING - ServerBindServiceImpl.getBindings() : Could not fetch bindings from cache for uuid 8cef7a4f-edc6-4345-b79c-57bc9d8d259d. Fetching from repo and adding to cache 2018-02-16 18:00:40,432 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 8cef7a4f-edc6-4345-b79c-57bc9d8d259d is now HB_FAILED. handle = 10.105.3.137 at 1518771640432 2018-02-16 18:00:40,433 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 8cef7a4f-edc6-4345-b79c-57bc9d8d259d is now STARTING. handle = 10.105.3.137 at 1518771640433 2018-02-16 18:00:40,466 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ClusterService 2018-02-16 18:00:40,476 main INFO SERVICES.CACHE - EHCacheFlush.init() : Initialize EHCacheFlush clear expired elements every 300 seconds 2018-02-16 18:00:40,477 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CacheService 2018-02-16 18:00:40,607 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AccessControl 2018-02-16 18:00:40,607 main INFO IDENTITY_ZONE - IdentityZoneServiceImpl.start() : Starting Identity Zone Api service... 2018-02-16 18:00:40,608 main INFO IDENTITY_ZONE - DataStoreFactory.init() : In DataStoreFactory.init() properties : {cassandra.remote.max.connections.per.host=4, max.organization.set.size=200, cassandra.defaults.cql.connect.timeoutInMillis=10000, cql.consistencylevel=LOCAL_QUORUM, cassandra.defaults.cql.read.timeoutInMillis=12000, cassandra.core.connections.per.host=2, datastoretype=identityzone-datastore, cql.fetchsize=5000, keyspace=identityzone, cassandra.max.connections.per.host=10, cassandra.defaults.cql.max.schema.agreement.wait.seconds=10} 2018-02-16 18:00:40,612 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/identityzone-datastore/{uuid}, async is true 2018-02-16 18:00:40,615 main INFO IDENTITY_ZONE - DataStoreFactory.init() : DataStoreFactory.init() : DataStoreFactory initialized 2018-02-16 18:00:40,626 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : IdentityZoneService 2018-02-16 18:00:40,678 main WARN n.s.e.c.CacheConfiguration - CacheConfiguration.warnMaxEntriesLocalHeap() : Cache: auth_cache has a maxElementsInMemory of 0. This might lead to performance degradation or OutOfMemoryError at Terracotta client.From Ehcache 2.0 onwards this has been changed to mean a store with no capacity limit. Set it to 1 if you want no elements cached in memory 2018-02-16 18:00:40,678 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AuthenticationService 2018-02-16 18:00:40,680 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: ONE. 2018-02-16 18:00:40,680 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:40,683 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/auth-datastore/{uuid}, async is true 2018-02-16 18:00:40,689 main WARN n.s.e.c.CacheConfiguration - CacheConfiguration.warnMaxEntriesLocalHeap() : Cache: creds_cache has a maxElementsInMemory of 0. This might lead to performance degradation or OutOfMemoryError at Terracotta client.From Ehcache 2.0 onwards this has been changed to mean a store with no capacity limit. Set it to 1 if you want no elements cached in memory 2018-02-16 18:00:40,692 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : UserService 2018-02-16 18:00:40,698 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: ONE. 2018-02-16 18:00:40,698 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:40,706 main WARN n.s.e.c.CacheConfiguration - CacheConfiguration.warnMaxEntriesLocalHeap() : Cache: PermissionsCache has a maxElementsInMemory of 0. This might lead to performance degradation or OutOfMemoryError at Terracotta client.From Ehcache 2.0 onwards this has been changed to mean a store with no capacity limit. Set it to 1 if you want no elements cached in memory 2018-02-16 18:00:40,707 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ResourceService 2018-02-16 18:00:40,708 main INFO c.a.l.m.c.SourceCategoryFactory - SourceCategoryFactory.init() : init called with props: {log.category.nodejs.displayName=nodejs, log.category.nodejs.name=REPLACE_ME_IN_ETC_APIGEE_TOKEN_DEFAULT_PROPERTIES, http.pool.max.connections=50, log.categories=nodejs, connection.timeout=90000, socket.timeout=90000, http.pool.max.per.route=50} 2018-02-16 18:00:40,709 main INFO c.a.l.m.c.SourceCategoryFactory - SourceCategoryFactory.addSourceCategory() : SourceCategory added with name: REPLACE_ME_IN_ETC_APIGEE_TOKEN_DEFAULT_PROPERTIES displayName: nodejs 2018-02-16 18:00:40,862 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : LoggingService 2018-02-16 18:00:40,972 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ServerMetricsService 2018-02-16 18:00:41,374 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : SecurityService 2018-02-16 18:00:41,739 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/router/{uuid}, async is true 2018-02-16 18:00:41,743 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ApplicationDistributionService 2018-02-16 18:00:43,302 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ConfigurationService 2018-02-16 18:00:43,303 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CompanyService 2018-02-16 18:00:43,303 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : DeveloperAppService 2018-02-16 18:00:43,315 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/kms-datastore/{uuid}, async is true 2018-02-16 18:00:43,320 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : KeyManagementService 2018-02-16 18:00:43,324 main WARN n.s.e.c.CacheConfiguration - CacheConfiguration.warnMaxEntriesLocalHeap() : Cache: GatewayOrganizationCacheForKms has a maxElementsInMemory of 0. This might lead to performance degradation or OutOfMemoryError at Terracotta client.From Ehcache 2.0 onwards this has been changed to mean a store with no capacity limit. Set it to 1 if you want no elements cached in memory 2018-02-16 18:00:43,325 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : KMSOrganizationService 2018-02-16 18:00:43,333 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/dc-datastore/{uuid}, async is true 2018-02-16 18:00:43,338 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : DeveloperService 2018-02-16 18:00:43,339 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ApiProductService 2018-02-16 18:00:43,340 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AppService 2018-02-16 18:00:43,344 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : OAuth1ManagementService 2018-02-16 18:00:43,345 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : OAuth2ManagementService 2018-02-16 18:00:43,345 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : KeyValueMapService 2018-02-16 18:00:43,349 main WARN KEYVALUEMAP.COMMON - Configuration.getIntProperty() : {code:keyvaluemap.service.PropertyConfigurationError, severity:NONE, component:KVM, category: SERVICE_FAILURE, faultflag:YES keyvaluemaps.jsonStringLengthWarningThresholdInChars=null, using default value: 512000 2018-02-16 18:00:43,349 main WARN KEYVALUEMAP.COMMON - Configuration.getIntProperty() : {code:keyvaluemap.service.PropertyConfigurationError, severity:NONE, component:KVM, category: SERVICE_FAILURE, faultflag:YES keyvaluemaps.warningThresholdLogIntervalInMins=null, using default value: 5 2018-02-16 18:00:43,350 main WARN KEYVALUEMAP.COMMON - Configuration.getIntProperty() : {code:keyvaluemap.service.PropertyConfigurationError, severity:NONE, component:KVM, category: SERVICE_FAILURE, faultflag:YES api.queryparam.kvm.count=null, using default value: 100 2018-02-16 18:00:43,356 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/keyvaluemap-datastore/{uuid}, async is true 2018-02-16 18:00:43,362 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : LegacyKeyValueMapService 2018-02-16 18:00:43,362 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CpsKeyValueMapService 2018-02-16 18:00:43,375 main INFO NODESCRIPT - NodeScriptServiceImpl.start() : Will cache up to 2000 compiled JavaScript files in memory 2018-02-16 18:00:43,380 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : NodeScriptService 2018-02-16 18:00:43,381 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : RebuildIndexService 2018-02-16 18:00:43,383 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : SecureStoreService 2018-02-16 18:00:43,384 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : JSONSubTypeRegisterService 2018-02-16 18:00:43,389 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/servers/{type}/{uuid}, async is false 2018-02-16 18:00:43,391 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/scopes/{scope}, async is false 2018-02-16 18:00:43,391 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}, async is false 2018-02-16 18:00:43,392 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/properties/{propname}, async is false 2018-02-16 18:00:43,392 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/consumer-groups/{consumergrpname}, async is false 2018-02-16 18:00:43,393 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/consumer-groups/{consumergrpname}/consumers/{consumergrpconsumerserver}, async is false 2018-02-16 18:00:43,396 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/consumer-groups/{consumergrpname}/datastores/{consumergrpds}, async is false 2018-02-16 18:00:43,397 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/data-processors/{processortype}, async is false 2018-02-16 18:00:43,397 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/data-processors/{processortype}/{processorname}, async is false 2018-02-16 18:00:43,398 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/data-processors/{processortype}/{processorname}/processors/{processoruuid}, async is false 2018-02-16 18:00:43,398 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/data-processors/{processortype}/{processorname}/datastores/{processordsuuid}, async is false 2018-02-16 18:00:43,399 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/data-processors/{processortype}/{processorname}/properties/{procpropname}, async is false 2018-02-16 18:00:43,399 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /analytics/groups/ax/{group_name}/consumer-groups/{consumergrpname}/properties/{consumergrpprops}, async is false 2018-02-16 18:00:43,404 main INFO QueryService - QueryServiceImpl.start() : Query service started 2018-02-16 18:00:43,404 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : QueryService 2018-02-16 18:00:43,405 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : DataAccessService 2018-02-16 18:00:43,415 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Filter on offline query enabled scopes: true 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Metadata Server: uap-metadata.apigee.net 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Metadata Server port: 0 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Consul Server: 10.16.34.11 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Query V2 Consul Server:{T}conf_query-service_uap.query.v2.consul.server{/T} 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Consul Server port: 8085 2018-02-16 18:00:43,416 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : UAP Region to AWS Region mapping: uap-e2e-us-east:us-east-1,uap-e2e-us-west:us-west-1,uap-us-east-prod:us-east-1,uap-prod-us-east-1:us-east-1,uap-eu-west-prod:eu-1,uap-prod-eu-west-1:eu-1,uap-us-west-prod:us-west-1,uap-prod-us-west-2:us-west-1,uap-devpod-us-east-1:us-east-1 2018-02-16 18:00:43,417 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Spark query timeout: 170 2018-02-16 18:00:43,417 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Spark query max allowed records: 14400 2018-02-16 18:00:43,417 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Registered Managed Object for : DataAPIResourceHandlerImpl as com.apigee.communication.RegistrationInfoImpl@33a39d7e 2018-02-16 18:00:43,418 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Registered Managed Object for : AdminResourceImpl as com.apigee.communication.RegistrationInfoImpl@77cd1f67 2018-02-16 18:00:43,418 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Online Spark Query Service enabled : false 2018-02-16 18:00:43,418 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Offline Spark Query Service enabled: false 2018-02-16 18:00:43,418 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Trial Pod Spark Query enabled: false 2018-02-16 18:00:43,418 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Message View Spark Query Service enabled:false 2018-02-16 18:00:43,419 main INFO c.a.a.d.i.DataApiServiceImpl - DataApiServiceImpl.start() : Metadata Cache timeout: 900000 2018-02-16 18:00:43,425 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : DataApiService 2018-02-16 18:00:43,430 main INFO c.a.a.r.i.ReportResourceImpl - ReportResourceImpl.getBasePath() : /{o:organizations|o}/{orgName}/reports/,/{o:organizations|o}/{orgName}/reports/{reportName} 2018-02-16 18:00:43,430 main INFO c.a.a.r.i.CustomAXReportServiceImpl - CustomAXReportServiceImpl.start() : Registered Managed Object for ReportResourceImpl 2018-02-16 18:00:43,430 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CustomReportApiService 2018-02-16 18:00:43,437 main INFO c.a.a.r.w.i.AnalyticsReportingResourceImpl - AnalyticsReportingResourceImpl.getBasePath() : Adding base paths:/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/reports/{reportType},/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/reports/{reportType}/{reportId},/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/reports/{reportType}/{reportId}/results,/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/reports/{reportType}/{reportId}/results/{resultId},/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/aggregates,/{o:organizations|o}/{orgName}/{e:e|envs|environments}/{env}/analytics/aggregates/{idOrName} 2018-02-16 18:00:43,437 main INFO c.a.a.r.AnalyticsReportingService - AnalyticsReportingService.start() : Started AnalyticsReportingApiService 2018-02-16 18:00:43,437 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AnalyticsReportingApiService 2018-02-16 18:00:43,439 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/user-settings-datastore/{uuid}, async is true 2018-02-16 18:00:43,443 main INFO c.a.a.s.r.AnalyticsSettingsRepoServerListener - AnalyticsSettingsRepoServerListener.init() : AnalyticsSettingsRepoServerListener.init() : Initializing datastores for application repository 2018-02-16 18:00:43,443 main INFO c.a.a.s.r.AnalyticsSettingsRepoServerListener - AnalyticsSettingsRepoServerListener.initializeDataStoreClient() : AnalyticsSettingsRepoServerListener.init() : Initializing data stores for application repository 2018-02-16 18:00:43,445 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: ONE. 2018-02-16 18:00:43,445 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:43,445 main INFO c.a.a.s.r.AnalyticsSettingsRepoServerListener - AnalyticsSettingsRepoServerListener.populateDataStoreConfig() : Adding server configs [] 2018-02-16 18:00:43,445 main INFO c.a.a.s.r.AnalyticsSettingsRepoServerListener - AnalyticsSettingsRepoServerListener.initializeDataStoreClient() : AnalyticsSettingsRepoServerListener.init() : No datastores configured for application repository 2018-02-16 18:00:43,445 main INFO c.a.a.s.i.AnalyticsSettingsResourceImpl - AnalyticsSettingsServiceImpl.start() : AnalyticsSettingsRepoServerListener listener registration done 2018-02-16 18:00:43,455 main INFO c.a.a.s.i.AnalyticsSettingsResourceImpl - AnalyticsSettingsServiceImpl.start() : Registered Managed Object for AnalyticsSettingsResourceImpl 2018-02-16 18:00:43,455 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : AnalyticsSettingsService 2018-02-16 18:00:43,458 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/analytics-datastore/{uuid}, async is true 2018-02-16 18:00:43,461 main INFO c.a.a.e.s.EntityRepoServerListener - EntityRepoServerListener.init() : EntityRepoServerListener.init() : Initializing datastores for application repository 2018-02-16 18:00:43,462 main INFO c.a.a.e.s.EntityRepoServerListener - EntityRepoServerListener.initializeDataStoreClient() : EntityRepoServerListener.init() : Initializing data stores for application repository 2018-02-16 18:00:43,462 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultReadConsistencyLevel() : Default read ConsistencyLevel set to: ONE. 2018-02-16 18:00:43,462 main INFO m.p.c.m.ConfigurableConsistencyLevel - ConfigurableConsistencyLevel.setDefaultWriteConsistencyLevel() : Default write ConsistencyLevel set to: ONE. 2018-02-16 18:00:43,462 main INFO c.a.a.e.s.EntityRepoServerListener - EntityRepoServerListener.populateDataStoreConfig() : Adding server configs [] 2018-02-16 18:00:43,462 main INFO c.a.a.e.s.EntityRepoServerListener - EntityRepoServerListener.initializeDataStoreClient() : EntityRepoServerListener.init() : No datastores configured for application repository 2018-02-16 18:00:43,463 main INFO c.a.a.e.s.EntityServiceImpl - EntityServiceImpl.start() : Started EntityServiceImpl 2018-02-16 18:00:43,463 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : ax-metadata-service 2018-02-16 18:00:43,467 main INFO c.a.a.c.i.CustomAggregatesApiResourceImpl - CustomAggregatesApiResourceImpl.getBasePath() : /{o:organizations|o}/{orgName}/stats/customaggregates , /analytics/customaggregates/delta 2018-02-16 18:00:43,467 main INFO c.a.a.c.a.CustomAggregatesApiService - CustomAggregatesApiService.start() : Started CustomAggregatesApiServiceImpl 2018-02-16 18:00:43,467 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CustomAggregatesApiService 2018-02-16 18:00:43,469 main INFO c.a.a.r.r.CustomRulesApiImpl - CustomRulesApiImpl.() : CustomRulesImpl: Custom rules API resource class has been initialized. 2018-02-16 18:00:43,470 main INFO c.a.a.r.r.CustomRulesApiImpl - InternalRulesApiImpl.() : InternalRulesApiImpl: Internal rules API resource class has been initialized. 2018-02-16 18:00:43,471 main INFO c.a.a.r.s.CustomRuleServiceImpl - CustomRuleServiceImpl.start() : start: Registered Managed Object for CustomRulesServiceImpl 2018-02-16 18:00:43,471 main INFO KERNEL.DEPLOYMENT - ServiceDeployer.startService() : ServiceDeployer.deploy() : Successfully started the service : CustomRulesService 2018-02-16 18:00:43,474 main INFO ZOOKEEPER - ZooKeeperServiceImpl.registerPathWatcher() : attaching watcher on path /regions/dc-1/pods/central/types/application-datastore/{uuid}, async is true 2018-02-16 18:00:43,731 main INFO REGISTRATION - ServerRegistrationServiceImpl.storeServerRegistrationPath() : Registering the server uuid 828dbaa7-c093-4451-883f-5b9bc5e2bf87 with region dc-1 and pod central information 2018-02-16 18:00:43,735 main INFO SERVICES.CACHE - CacheMemoryLimiter.init() : CacheMemoryLimiter on=true persentage of heap to use is=35 2018-02-16 18:00:43,735 main INFO SERVICES.CACHE - CacheMemoryLimiter.init() : CacheMemoryLimiter maxMemory is 355M. CacheLimit is 101M 2018-02-16 18:00:43,737 Apigee-Timer-5 INFO METRICSLOGGING - MetricsLogger.log() : Release id 160921_09 RPM apigee-rpm-1.0.0.1414.201d3e0.1711090346-160921_09 CACHE.total [queue_remaining=500] THREADPOOL.Main.statistics [activeThreadCount=1 completedTaskCount=2 corePoolSize=100 largestPoolSize=3 currentPoolSize=3 maxAllowedPoolSize=500] THREADPOOL.Timer.statistics [corePoolSize=10 currentPoolSize=10 activeThreadCount=1 completedTaskCount=17 maxAllowedPoolSize=2147483647 largestPoolSize=10] ZOOKEEPER.configuration [connectInfo=State:CONNECTED Timeout:40000 sessionid:0x2619ce2a3c60002 local:/10.105.3.137:37532 remoteserver:10.105.3.135/10.105.3.135:2181 lastZxid:12884902176 xid:1013 sent:1013 recv:1014 queuedpkts:0 pendingresp:0 queuedevents:0] ZOOKEEPER.counters [operations=963 successfulOperations=956] ZOOKEEPER.operations [exists=704 setData=25 getData=126 getChildren=108] ZOOKEEPER.pathWatcher [create=34 add=6] ZOOKEEPER.statistics [operations=956] 2018-02-16 18:00:43,739 main INFO SERVICES.CACHE - CacheServiceImpl$2.run() : Successfully initialized cache memory limiter 2018-02-16 18:00:43,739 main INFO KERNEL - MicroKernel.main() : MicroKernel.main() : MicroKernel started successfully, proceeding to wait indefinitely. 2018-02-16 18:00:44,388 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of f8f20707-e02f-492a-bf3d-ec9d88e8a0ea is now CONNECTED. handle = 10.105.3.137 at 1518771644388 2018-02-16 18:00:44,393 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 5b9dbfe0-6bda-40fe-8242-14f2b0b2ecf3 is now CONNECTED. handle = 10.105.3.137 at 1518771644393 2018-02-16 18:00:44,393 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 406bb057-25eb-47a4-a724-eaacfd575640 is now CONNECTED. handle = 10.105.3.137 at 1518771644393 2018-02-16 18:00:44,419 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of efe0149c-a0ec-4ae8-807e-e2418b4b73c4 is now CONNECTED. handle = 10.105.3.137 at 1518771644419 2018-02-16 18:00:44,433 RPCClientClientProtocolChildGroup-RPC-0 INFO CLUSTER - ServerState.setState() : State of 8cef7a4f-edc6-4345-b79c-57bc9d8d259d is now CONNECTED. handle = 10.105.3.137 at 1518771644433