By type
cds-cluster:dev-cds-blueprints-processor-568978cc79-fssbp APP Config HOME : /opt/app/onap/config
Warning: use -cacerts option to access cacerts keystore
Certificate was added to keystore
23:02:30,675 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
23:02:30,676 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
23:02:30,677 |-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]
23:02:30,678 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
23:02:30,678 |-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]
23:02:30,678 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml]
23:02:30,700 |-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
23:02:30,829 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
23:02:30,841 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
23:02:30,845 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
23:02:30,853 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
23:02:30,926 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO
23:02:30,927 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO
23:02:30,927 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR
23:02:30,927 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR
23:02:30,927 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO
23:02:30,927 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
23:02:30,927 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
23:02:30,928 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
23:02:30,929 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2925bf5b - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.3.8.RELEASE)
2021-04-12 23:02:31,731|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.1.2 on dev-cds-blueprints-processor-568978cc79-fssbp 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-12 23:02:31,734|||main||||INFO||||||| No active profile set, falling back to default profiles: default
2021-04-12 23:02:35,007|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2021-04-12 23:02:35,608|||main||||INFO||||||| Finished Spring Data repository scanning in 588ms. Found 10 JPA repository interfaces.
2021-04-12 23:02:38,437|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default]
2021-04-12 23:02:38,509|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.4.27.Final
2021-04-12 23:02:38,874|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2021-04-12 23:02:39,302|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2021-04-12 23:02:39,626|||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-12 23:02:39,630|||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-12 23:02:39,829|||main||||INFO||||||| HV000001: Hibernate Validator 6.1.7.Final
2021-04-12 23:02:41,015|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2021-04-12 23:02:41,027|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-04-12 23:02:41,131|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties...
2021-04-12 23:02:42,455|||main||||INFO||||||| Audit service is disabled
2021-04-12 23:02:42,672|||main||||INFO||||||| Dependency Management module created...
2021-04-12 23:02:43,296|||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-12 23:02:45,314|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator'
2021-04-12 23:02:45,617|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mysql://cds-db:3306/sdnctl
2021-04-12 23:02:47,631|||main||||INFO||||||| Netty started on port(s): 8080
2021-04-12 23:02:49,756|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting..
2021-04-12 23:02:50,438|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)...
2021-04-12 23:02:50,451|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 19.389 seconds (JVM running for 20.384)
2021-04-12 23:02:50,484|||DefaultDispatcher-worker-1||||INFO||||||| ****** Cluster configuration file(/opt/app/onap/config/hazelcast.yaml) ****
Apr 12, 2021 11:02:50 PM com.hazelcast.config.FileSystemYamlConfig
INFO: Configuring Hazelcast from '/opt/app/onap/config/hazelcast.yaml'.
Apr 12, 2021 11:02:50 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-12 23:02:50,760|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Prefer IPv4 stack is true, prefer IPv6 addresses is false
2021-04-12 23:02:50,776|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Picked [10.242.240.142]:5701, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=5701], bind any local is true
2021-04-12 23:02:50,807|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Hazelcast 4.0 (20200204 - 8b959f5) starting at [10.242.240.142]:5701
2021-04-12 23:02:50,808|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Copyright (c) 2008-2020, Hazelcast, Inc. All Rights Reserved.
2021-04-12 23:02:51,539|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Backpressure is disabled
2021-04-12 23:02:51,761|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]: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-12 23:02:51,764|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Kubernetes Discovery activated with mode: KUBERNETES_API
2021-04-12 23:02:51,868|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Activating Discovery SPI Joiner
2021-04-12 23:02:51,873|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is enabled with 3 members.
2021-04-12 23:02:52,218|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Starting 2 partition threads and 3 generic threads (1 dedicated for priority tasks)
2021-04-12 23:02:52,226|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
2021-04-12 23:02:52,233|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] [10.242.240.142]: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-12 23:02:52,704|||DefaultDispatcher-worker-1||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Cannot fetch the current zone, ZONE_AWARE feature is disabled
2021-04-12 23:02:52,859|||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-12 23:02:52,912|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-2||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Connecting to /10.242.149.69:5701, timeout: 10000, bind-any: true
2021-04-12 23:02:52,916|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Connecting to /10.242.53.6:5701, timeout: 10000, bind-any: true
2021-04-12 23:02:52,919|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.53.6:5701. Reason: SocketException[Connection refused to address /10.242.53.6:5701]
2021-04-12 23:02:52,919|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] [10.242.53.6]:5701 is added to the blacklist.
2021-04-12 23:02:53,023|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.IO.thread-in-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.240.142:35043 and /10.242.149.69:5701
2021-04-12 23:02:59,920|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.priority-generic-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
Members {size:2, ver:2} [
Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac
Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this
]
2021-04-12 23:03:00,227|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:00,902|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] [10.242.240.142]:5701 is STARTED
2021-04-12 23:03:05,237|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-2||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:10,250|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-2||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:15,258|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-6||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:20,261|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-6||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:25,290|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-4||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:30,327|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:30,875|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.IO.thread-in-2||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.240.142:5701 and /10.242.53.6:49519
2021-04-12 23:03:35,387|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-3||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-12 23:03:37,881|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.generic-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
Members {size:3, ver:3} [
Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac
Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this
Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27
]
2021-04-12 23:03:38,430|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-7||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] RaftNode[CPGroupId{name='METADATA', seed=0, groupId=0}] is created with [RaftEndpoint{uuid='da4059fb-e96a-4af1-b18e-a1df5c24d0e5'}, RaftEndpoint{uuid='1c047d20-e009-43a3-9f6b-c8305229ef27'}, RaftEndpoint{uuid='3cfb1a26-4bd7-4503-87b8-fae839b806ac'}]
2021-04-12 23:03:38,446|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-12 23:03:40,571|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Granted pre-vote for PreVoteRequest{candidate=RaftEndpoint{uuid='3cfb1a26-4bd7-4503-87b8-fae839b806ac'}, nextTerm=1, lastLogTerm=0, lastLogIndex=0}
2021-04-12 23:03:40,577|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Moving to new term: 1 from current term: 0 after VoteRequest{candidate=RaftEndpoint{uuid='3cfb1a26-4bd7-4503-87b8-fae839b806ac'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-12 23:03:40,577|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701} - FOLLOWER this
CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701}
CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701}
]
2021-04-12 23:03:40,577|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Granted vote for VoteRequest{candidate=RaftEndpoint{uuid='3cfb1a26-4bd7-4503-87b8-fae839b806ac'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-12 23:03:40,602|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Setting leader: RaftEndpoint{uuid='3cfb1a26-4bd7-4503-87b8-fae839b806ac'}
2021-04-12 23:03:40,603|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701} - FOLLOWER this
CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701}
CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701} - LEADER
]
2021-04-12 23:03:41,081|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-7||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CP Subsystem is initialized with: [CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701}, CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701}, CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701}]
2021-04-12 23:03:41,092|||DefaultDispatcher-worker-1||||INFO||||||| Promoted as CP member(Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this)
2021-04-12 23:03:41,111|||DefaultDispatcher-worker-1||||INFO||||||| CP Members(3): [CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701}, CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701}, CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701}]
2021-04-12 23:03:41,115|||DefaultDispatcher-worker-1||||INFO||||||| CP Group Members(3): [CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701}, CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701}, CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701}]
2021-04-12 23:03:41,118|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-fssbp) created successfully....
2021-04-12 23:03:41,121|||DefaultDispatcher-worker-1||||INFO||||||| Registering BlueprintCompilerCacheMessageListener
2021-04-12 23:03:41,125|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-fssbp) listening to topic(BLUEPRINT_CLEAN_COMPILER_CACHE)...
2021-04-12 23:06:07,218|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-7||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Suspecting Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27 because it has not sent any heartbeats since 2021-04-12 23:05:05.350. Now: 2021-04-12 23:06:07.209, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-12 23:06:07,220|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-7||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27 is suspected to be dead for reason: Suspecting Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27 because it has not sent any heartbeats since 2021-04-12 23:05:05.350. Now: 2021-04-12 23:06:07.209, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-12 23:06:07,220|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-7||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Connection[id=2, /10.242.240.142:5701->/10.242.53.6:49519, qualifier=null, endpoint=[10.242.53.6]:5701, alive=false, connectionType=MEMBER] closed. Reason: Suspecting Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27 because it has not sent any heartbeats since 2021-04-12 23:05:05.350. Now: 2021-04-12 23:06:07.209, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-12 23:06:08,877|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.generic-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
Members {size:2, ver:4} [
Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac
Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this
]
2021-04-12 23:06:08,877|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.event-3||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.53.6]:5701 - 1c047d20-e009-43a3-9f6b-c8305229ef27, type=removed, members=[Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac, Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this]}
2021-04-12 23:06:08,885|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-2||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Committing/rolling-back live transactions of [10.242.53.6]:5701, UUID: 1c047d20-e009-43a3-9f6b-c8305229ef27
2021-04-12 23:06:08,888|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.cached.thread-2||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701} is not present in the cluster. It will be auto-removed after 120 seconds.
2021-04-12 23:08:09,880|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701} will be removed from [CPGroupId{name='METADATA', seed=0, groupId=0}]
2021-04-12 23:08:10,097|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Status is set to: UPDATING_GROUP_MEMBER_LIST
2021-04-12 23:08:10,098|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:2, term:1, logIndex:7} [
CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701} - FOLLOWER this
CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701} - LEADER
]
2021-04-12 23:08:10,098|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-12 23:08:10,106|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] CPMember{uuid=1c047d20-e009-43a3-9f6b-c8305229ef27, address=[10.242.53.6]:5701} is removed from CP Subsystem.
2021-04-12 23:16:39,191|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.IO.thread-in-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.240.142:5701 and /10.242.53.6:56339
2021-04-12 23:16:57,326|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.IO.thread-in-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Connection[id=3, /10.242.240.142:5701->/10.242.53.6:56339, qualifier=null, endpoint=[10.242.53.6]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-12 23:19:18,622|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.IO.thread-in-1||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.240.142:5701 and /10.242.111.159:36799
2021-04-12 23:19:25,619|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.priority-generic-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
Members {size:3, ver:5} [
Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac
Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this
Member [10.242.111.159]:5701 - ba3f2e03-01b2-43af-9c22-a3aa64c7631f
]
2021-04-12 23:19:25,621|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.event-3||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.111.159]:5701 - ba3f2e03-01b2-43af-9c22-a3aa64c7631f, type=added, members=[Member [10.242.149.69]:5701 - 3cfb1a26-4bd7-4503-87b8-fae839b806ac, Member [10.242.240.142]:5701 - da4059fb-e96a-4af1-b18e-a1df5c24d0e5 this, Member [10.242.111.159]:5701 - ba3f2e03-01b2-43af-9c22-a3aa64c7631f]}
2021-04-12 23:19:31,715|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Added new CPMember{uuid=d92c2371-0aea-4436-8ef4-2d348a9dc084, address=[10.242.111.159]:5701}. New active CP members list: [CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701}, CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701}, CPMember{uuid=d92c2371-0aea-4436-8ef4-2d348a9dc084, address=[10.242.111.159]:5701}]
2021-04-12 23:19:32,091|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||WARN||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Status is set to: UPDATING_GROUP_MEMBER_LIST
2021-04-12 23:19:32,092|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:10} [
CPMember{uuid=da4059fb-e96a-4af1-b18e-a1df5c24d0e5, address=[10.242.240.142]:5701} - FOLLOWER this
CPMember{uuid=3cfb1a26-4bd7-4503-87b8-fae839b806ac, address=[10.242.149.69]:5701} - LEADER
CPMember{uuid=d92c2371-0aea-4436-8ef4-2d348a9dc084, address=[10.242.111.159]:5701}
]
2021-04-12 23:19:32,100|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-12 23:19:32,107|||hz.dev-cds-blueprints-processor-568978cc79-fssbp.partition-operation.thread-0||||INFO||||||| [10.242.240.142]:5701 [cds-cluster] [4.0] Rebalancing is completed.