By type
cds-cluster:dev-cds-blueprints-processor-568978cc79-kl7r6 APP Config HOME : /opt/app/onap/config
Warning: use -cacerts option to access cacerts keystore
Certificate was added to keystore
22:52:09,957 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
22:52:09,958 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
22:52:09,959 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.1.2.jar!/logback.xml]
22:52:09,960 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
22:52:09,960 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.1.2.jar!/logback.xml]
22:52:09,960 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml]
22:52:09,985 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@45c7e403 - URL [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.1.2.jar!/logback.xml] is not of type file
22:52:10,118 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
22:52:10,131 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
22:52:10,137 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
22:52:10,145 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO
22:52:10,240 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
22:52:10,240 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
22:52:10,241 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
22:52:10,243 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2925bf5b - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.3.8.RELEASE)
2021-04-20 22:52:10,957|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.1.2 on dev-cds-blueprints-processor-568978cc79-kl7r6 with PID 1 (/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.1.2.jar started by onap in /app)
2021-04-20 22:52:10,960|||main||||INFO||||||| No active profile set, falling back to default profiles: default
2021-04-20 22:52:13,512|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2021-04-20 22:52:14,087|||main||||INFO||||||| Finished Spring Data repository scanning in 563ms. Found 10 JPA repository interfaces.
2021-04-20 22:52:16,358|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default]
2021-04-20 22:52:16,440|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.4.27.Final
2021-04-20 22:52:16,714|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2021-04-20 22:52:17,097|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2021-04-20 22:52:17,455|||main||||WARN||||||| HHH000481: Encountered Java type [class com.fasterxml.jackson.databind.JsonNode] for which we could not locate a JavaTypeDescriptor and which does not appear to implement equals and/or hashCode. This can lead to significant performance problems when performing equality/dirty checking involving this Java type. Consider registering a custom JavaTypeDescriptor or at least implementing equals/hashCode.
2021-04-20 22:52:17,459|||main||||WARN||||||| HHH000481: Encountered Java type [class org.onap.ccsdk.cds.controllerblueprints.resource.dict.ResourceDefinition] for which we could not locate a JavaTypeDescriptor and which does not appear to implement equals and/or hashCode. This can lead to significant performance problems when performing equality/dirty checking involving this Java type. Consider registering a custom JavaTypeDescriptor or at least implementing equals/hashCode.
2021-04-20 22:52:17,661|||main||||INFO||||||| HV000001: Hibernate Validator 6.1.7.Final
2021-04-20 22:52:18,500|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2021-04-20 22:52:18,514|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-04-20 22:52:18,616|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties...
2021-04-20 22:52:19,777|||main||||INFO||||||| Audit service is disabled
2021-04-20 22:52:19,953|||main||||INFO||||||| Dependency Management module created...
2021-04-20 22:52:20,412|||main||||INFO||||||| Registering Dictionary Sources : [processor-db=source-db, input=source-input, default=source-default, sdnc=source-rest, aai-data=source-rest, capability=source-capability, rest=source-rest, vault-data=source-rest, script=source-capability]
2021-04-20 22:52:21,997|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator'
2021-04-20 22:52:22,225|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mysql://cds-db:3306/sdnctl
2021-04-20 22:52:23,346|||main||||INFO||||||| Netty started on port(s): 8080
2021-04-20 22:52:24,755|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting..
2021-04-20 22:52:25,131|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)...
2021-04-20 22:52:25,139|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 14.746 seconds (JVM running for 15.765)
2021-04-20 22:52:25,165|||DefaultDispatcher-worker-1||||INFO||||||| ****** Cluster configuration file(/opt/app/onap/config/hazelcast.yaml) ****
Apr 20, 2021 10:52:25 PM com.hazelcast.config.FileSystemYamlConfig
INFO: Configuring Hazelcast from '/opt/app/onap/config/hazelcast.yaml'.
Apr 20, 2021 10:52:25 PM com.hazelcast.instance.impl.HazelcastInstanceFactory
WARNING: Hazelcast is starting in a Java modular environment (Java 9 and newer) but without proper access to required Java packages. Use additional Java arguments to provide Hazelcast access to Java internal API. The internal API access is used to get the best performance results. Arguments to be used:
--add-modules java.se --add-exports java.base/jdk.internal.ref=ALL-UNNAMED --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.management/sun.management=ALL-UNNAMED --add-opens jdk.management/com.sun.management.internal=ALL-UNNAMED
2021-04-20 22:52:25,372|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Prefer IPv4 stack is true, prefer IPv6 addresses is false
2021-04-20 22:52:25,382|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Picked [10.242.85.197]:5701, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=5701], bind any local is true
2021-04-20 22:52:25,406|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Hazelcast 4.0 (20200204 - 8b959f5) starting at [10.242.85.197]:5701
2021-04-20 22:52:25,406|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Copyright (c) 2008-2020, Hazelcast, Inc. All Rights Reserved.
2021-04-20 22:52:25,926|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Backpressure is disabled
2021-04-20 22:52:26,148|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Kubernetes Discovery properties: { service-dns: null, service-dns-timeout: 5, service-name: cds-blueprints-processor-cluster, service-port: 0, service-label: null, service-label-value: true, namespace: onap, pod-label: null, pod-label-value: null, resolve-not-ready-addresses: true, use-node-name-as-external-address: false, kubernetes-api-retries: 3, kubernetes-master: https://kubernetes.default.svc}
2021-04-20 22:52:26,152|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Kubernetes Discovery activated with mode: KUBERNETES_API
2021-04-20 22:52:26,245|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Activating Discovery SPI Joiner
2021-04-20 22:52:26,251|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] CP Subsystem is enabled with 3 members.
2021-04-20 22:52:26,552|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Starting 2 partition threads and 3 generic threads (1 dedicated for priority tasks)
2021-04-20 22:52:26,554|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
2021-04-20 22:52:26,559|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] [10.242.85.197]:5701 is STARTING
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.hazelcast.internal.networking.nio.SelectorOptimizer (file:/opt/app/onap/lib/com.hazelcast-hazelcast-all-4.0.jar) to field sun.nio.ch.SelectorImpl.selectedKeys
WARNING: Please consider reporting this to the maintainers of com.hazelcast.internal.networking.nio.SelectorOptimizer
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
2021-04-20 22:52:26,992|||DefaultDispatcher-worker-1||||WARN||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Cannot fetch the current zone, ZONE_AWARE feature is disabled
2021-04-20 22:52:27,138|||DefaultDispatcher-worker-1||||WARN||||||| Cannot fetch public IPs of Hazelcast Member PODs, you won't be able to use Hazelcast Smart Client from outside of the Kubernetes network
2021-04-20 22:52:27,162|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.cached.thread-2||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Connecting to /10.242.150.143:5701, timeout: 10000, bind-any: true
2021-04-20 22:52:27,162|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.cached.thread-3||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Connecting to /10.242.223.131:5701, timeout: 10000, bind-any: true
2021-04-20 22:52:27,177|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.IO.thread-in-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.85.197:35281 and /10.242.150.143:5701
2021-04-20 22:52:27,177|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.IO.thread-in-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.85.197:43029 and /10.242.223.131:5701
2021-04-20 22:52:33,190|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.priority-generic-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0]
Members {size:3, ver:3} [
Member [10.242.150.143]:5701 - 3fcb60df-7727-4729-9885-cef77ca581f2
Member [10.242.223.131]:5701 - 470d6b57-5e22-4988-bf4d-62d9a6085081
Member [10.242.85.197]:5701 - cf82f183-4e79-47d4-a3d5-c48aa5417b43 this
]
2021-04-20 22:52:33,579|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.cached.thread-2||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] RaftNode[CPGroupId{name='METADATA', seed=0, groupId=0}] is created with [RaftEndpoint{uuid='cf82f183-4e79-47d4-a3d5-c48aa5417b43'}, RaftEndpoint{uuid='3fcb60df-7727-4729-9885-cef77ca581f2'}, RaftEndpoint{uuid='470d6b57-5e22-4988-bf4d-62d9a6085081'}]
2021-04-20 22:52:33,582|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-20 22:52:34,188|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] [10.242.85.197]:5701 is STARTED
2021-04-20 22:52:35,534|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Granted pre-vote for PreVoteRequest{candidate=RaftEndpoint{uuid='3fcb60df-7727-4729-9885-cef77ca581f2'}, nextTerm=1, lastLogTerm=0, lastLogIndex=0}
2021-04-20 22:52:35,544|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Moving to new term: 1 from current term: 0 after VoteRequest{candidate=RaftEndpoint{uuid='3fcb60df-7727-4729-9885-cef77ca581f2'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-20 22:52:35,545|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=cf82f183-4e79-47d4-a3d5-c48aa5417b43, address=[10.242.85.197]:5701} - FOLLOWER this
CPMember{uuid=3fcb60df-7727-4729-9885-cef77ca581f2, address=[10.242.150.143]:5701}
CPMember{uuid=470d6b57-5e22-4988-bf4d-62d9a6085081, address=[10.242.223.131]:5701}
]
2021-04-20 22:52:35,545|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Granted vote for VoteRequest{candidate=RaftEndpoint{uuid='3fcb60df-7727-4729-9885-cef77ca581f2'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-20 22:52:35,571|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] Setting leader: RaftEndpoint{uuid='3fcb60df-7727-4729-9885-cef77ca581f2'}
2021-04-20 22:52:35,572|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.partition-operation.thread-0||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=cf82f183-4e79-47d4-a3d5-c48aa5417b43, address=[10.242.85.197]:5701} - FOLLOWER this
CPMember{uuid=3fcb60df-7727-4729-9885-cef77ca581f2, address=[10.242.150.143]:5701} - LEADER
CPMember{uuid=470d6b57-5e22-4988-bf4d-62d9a6085081, address=[10.242.223.131]:5701}
]
2021-04-20 22:52:36,444|||hz.dev-cds-blueprints-processor-568978cc79-kl7r6.cached.thread-2||||INFO||||||| [10.242.85.197]:5701 [cds-cluster] [4.0] CP Subsystem is initialized with: [CPMember{uuid=cf82f183-4e79-47d4-a3d5-c48aa5417b43, address=[10.242.85.197]:5701}, CPMember{uuid=3fcb60df-7727-4729-9885-cef77ca581f2, address=[10.242.150.143]:5701}, CPMember{uuid=470d6b57-5e22-4988-bf4d-62d9a6085081, address=[10.242.223.131]:5701}]
2021-04-20 22:52:36,448|||DefaultDispatcher-worker-1||||INFO||||||| Promoted as CP member(Member [10.242.85.197]:5701 - cf82f183-4e79-47d4-a3d5-c48aa5417b43 this)
2021-04-20 22:52:36,479|||DefaultDispatcher-worker-1||||INFO||||||| CP Members(3): [CPMember{uuid=cf82f183-4e79-47d4-a3d5-c48aa5417b43, address=[10.242.85.197]:5701}, CPMember{uuid=3fcb60df-7727-4729-9885-cef77ca581f2, address=[10.242.150.143]:5701}, CPMember{uuid=470d6b57-5e22-4988-bf4d-62d9a6085081, address=[10.242.223.131]:5701}]
2021-04-20 22:52:36,482|||DefaultDispatcher-worker-1||||INFO||||||| CP Group Members(3): [CPMember{uuid=cf82f183-4e79-47d4-a3d5-c48aa5417b43, address=[10.242.85.197]:5701}, CPMember{uuid=3fcb60df-7727-4729-9885-cef77ca581f2, address=[10.242.150.143]:5701}, CPMember{uuid=470d6b57-5e22-4988-bf4d-62d9a6085081, address=[10.242.223.131]:5701}]
2021-04-20 22:52:36,483|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-kl7r6) created successfully....
2021-04-20 22:52:36,487|||DefaultDispatcher-worker-1||||INFO||||||| Registering BlueprintCompilerCacheMessageListener
2021-04-20 22:52:36,488|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-kl7r6) listening to topic(BLUEPRINT_CLEAN_COMPILER_CACHE)...