By type
cds-cluster:dev-cds-blueprints-processor-568978cc79-jlnqv APP Config HOME : /opt/app/onap/config
Warning: use -cacerts option to access cacerts keystore
Certificate was added to keystore
14:36:47,909 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:36:47,910 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
14:36:47,911 |-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]
14:36:47,912 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
14:36:47,912 |-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]
14:36:47,912 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml]
14:36:47,937 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@2925bf5b - 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
14:36:48,081 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
14:36:48,092 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
14:36:48,097 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
14:36:48,107 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:36:48,205 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO
14:36:48,205 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO
14:36:48,205 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR
14:36:48,205 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR
14:36:48,205 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO
14:36:48,206 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
14:36:48,206 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
14:36:48,207 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
14:36:48,209 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@710f4dc7 - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.3.8.RELEASE)
2021-04-08 14:36:49,136|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.1.2 on dev-cds-blueprints-processor-568978cc79-jlnqv 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-08 14:36:49,139|||main||||INFO||||||| No active profile set, falling back to default profiles: default
2021-04-08 14:36:52,364|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2021-04-08 14:36:53,180|||main||||INFO||||||| Finished Spring Data repository scanning in 799ms. Found 10 JPA repository interfaces.
2021-04-08 14:36:55,330|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default]
2021-04-08 14:36:55,401|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.4.27.Final
2021-04-08 14:36:55,621|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2021-04-08 14:36:55,980|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2021-04-08 14:36:56,300|||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-08 14:36:56,305|||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-08 14:36:56,536|||main||||INFO||||||| HV000001: Hibernate Validator 6.1.7.Final
2021-04-08 14:36:57,378|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2021-04-08 14:36:57,395|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default'
2021-04-08 14:36:57,498|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties...
2021-04-08 14:36:58,810|||main||||INFO||||||| Audit service is disabled
2021-04-08 14:36:59,002|||main||||INFO||||||| Dependency Management module created...
2021-04-08 14:36:59,503|||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-08 14:37:01,198|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator'
2021-04-08 14:37:01,466|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mysql://cds-db:3306/sdnctl
2021-04-08 14:37:02,716|||main||||INFO||||||| Netty started on port(s): 8080
2021-04-08 14:37:04,032|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting..
2021-04-08 14:37:04,374|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)...
2021-04-08 14:37:04,380|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 15.999 seconds (JVM running for 17.099)
2021-04-08 14:37:04,402|||DefaultDispatcher-worker-1||||INFO||||||| ****** Cluster configuration file(/opt/app/onap/config/hazelcast.yaml) ****
Apr 08, 2021 2:37:04 PM com.hazelcast.config.FileSystemYamlConfig
INFO: Configuring Hazelcast from '/opt/app/onap/config/hazelcast.yaml'.
Apr 08, 2021 2:37:04 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-08 14:37:04,643|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Prefer IPv4 stack is true, prefer IPv6 addresses is false
2021-04-08 14:37:04,658|||DefaultDispatcher-worker-1||||INFO||||||| [LOCAL] [cds-cluster] [4.0] Picked [10.242.8.135]:5701, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=5701], bind any local is true
2021-04-08 14:37:04,692|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Hazelcast 4.0 (20200204 - 8b959f5) starting at [10.242.8.135]:5701
2021-04-08 14:37:04,692|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Copyright (c) 2008-2020, Hazelcast, Inc. All Rights Reserved.
2021-04-08 14:37:05,207|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Backpressure is disabled
2021-04-08 14:37:05,463|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]: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-08 14:37:05,467|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Kubernetes Discovery activated with mode: KUBERNETES_API
2021-04-08 14:37:05,550|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Activating Discovery SPI Joiner
2021-04-08 14:37:05,555|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is enabled with 3 members.
2021-04-08 14:37:05,894|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Starting 2 partition threads and 3 generic threads (1 dedicated for priority tasks)
2021-04-08 14:37:05,902|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
2021-04-08 14:37:05,912|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] [10.242.8.135]: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-08 14:37:06,305|||DefaultDispatcher-worker-1||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Cannot fetch the current zone, ZONE_AWARE feature is disabled
2021-04-08 14:37:06,470|||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-08 14:37:06,486|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-3||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 14:37:06,487|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.254.9:5701, timeout: 10000, bind-any: true
2021-04-08 14:37:06,491|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.254.9:5701. Reason: SocketException[Connection refused to address /10.242.254.9:5701]
2021-04-08 14:37:06,492|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] [10.242.254.9]:5701 is added to the blacklist.
2021-04-08 14:37:06,519|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:44015 and /10.242.139.198:5701
2021-04-08 14:37:12,551|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:2, ver:2} [
Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
]
2021-04-08 14:37:12,891|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:13,529|||DefaultDispatcher-worker-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] [10.242.8.135]:5701 is STARTED
2021-04-08 14:37:17,897|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:22,899|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:27,901|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-5||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:32,903|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:37,909|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:42,911|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:47,914|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:52,916|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:37:57,918|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:02,920|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:07,922|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:12,924|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:17,927|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-4||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:22,930|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:27,932|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:32,936|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:37,938|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:42,940|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:46,507|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.254.9:54955
2021-04-08 14:38:47,955|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is waiting for 3 members to join the cluster. Current member count: 2
2021-04-08 14:38:52,567|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.generic-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:3, ver:3} [
Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
]
2021-04-08 14:38:52,982|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] RaftNode[CPGroupId{name='METADATA', seed=0, groupId=0}] is created with [RaftEndpoint{uuid='be38e4e4-4184-46f0-a0ee-d3f8b47313a1'}, RaftEndpoint{uuid='fdc474b9-9dda-48d7-972e-1c0311e7eac8'}, RaftEndpoint{uuid='2404658c-3b7d-4eee-a10d-e0db3a562b8d'}]
2021-04-08 14:38:52,987|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-08 14:38:55,510|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Granted pre-vote for PreVoteRequest{candidate=RaftEndpoint{uuid='fdc474b9-9dda-48d7-972e-1c0311e7eac8'}, nextTerm=1, lastLogTerm=0, lastLogIndex=0}
2021-04-08 14:38:55,516|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Moving to new term: 1 from current term: 0 after VoteRequest{candidate=RaftEndpoint{uuid='fdc474b9-9dda-48d7-972e-1c0311e7eac8'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-08 14:38:55,517|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701}
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
]
2021-04-08 14:38:55,517|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Granted vote for VoteRequest{candidate=RaftEndpoint{uuid='fdc474b9-9dda-48d7-972e-1c0311e7eac8'}, term=1, lastLogTerm=0, lastLogIndex=0, disruptive=false}
2021-04-08 14:38:55,529|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Setting leader: RaftEndpoint{uuid='fdc474b9-9dda-48d7-972e-1c0311e7eac8'}
2021-04-08 14:38:55,529|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:0} [
CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701}
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} - LEADER
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
]
2021-04-08 14:38:56,878|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CP Subsystem is initialized with: [CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701}, CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}, CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701}]
2021-04-08 14:38:56,887|||DefaultDispatcher-worker-1||||INFO||||||| Promoted as CP member(Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this)
2021-04-08 14:38:56,900|||DefaultDispatcher-worker-1||||INFO||||||| CP Members(3): [CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701}, CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}, CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701}]
2021-04-08 14:38:56,909|||DefaultDispatcher-worker-1||||INFO||||||| CP Group Members(3): [CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701}, CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}, CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701}]
2021-04-08 14:38:56,912|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-jlnqv) created successfully....
2021-04-08 14:38:56,914|||DefaultDispatcher-worker-1||||INFO||||||| Registering BlueprintCompilerCacheMessageListener
2021-04-08 14:38:56,914|||DefaultDispatcher-worker-1||||INFO||||||| Cluster(cds-cluster) node(dev-cds-blueprints-processor-568978cc79-jlnqv) listening to topic(BLUEPRINT_CLEAN_COMPILER_CACHE)...
2021-04-08 14:41:05,888|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-6||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Suspecting Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1 because it has not sent any heartbeats since 2021-04-08 14:39:56.967. Now: 2021-04-08 14:41:01.159, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-08 14:41:05,888|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-6||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1 is suspected to be dead for reason: Suspecting Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1 because it has not sent any heartbeats since 2021-04-08 14:39:56.967. Now: 2021-04-08 14:41:01.159, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-08 14:41:05,889|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-6||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=1, /10.242.8.135:44015->/10.242.139.198:5701, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Suspecting Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1 because it has not sent any heartbeats since 2021-04-08 14:39:56.967. Now: 2021-04-08 14:41:01.159, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-08 14:41:05,892|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-6||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Starting mastership claim process...
2021-04-08 14:41:05,893|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-6||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Local MembersView{version=3, members=[MemberInfo{address=[10.242.139.198]:5701, uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, liteMember=false, memberListJoinVersion=1}, MemberInfo{address=[10.242.8.135]:5701, uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, liteMember=false, memberListJoinVersion=2}, MemberInfo{address=[10.242.254.9]:5701, uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, liteMember=false, memberListJoinVersion=3}]} with suspected members: [[10.242.139.198]:5701] and initial addresses to ask: [Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8]
2021-04-08 14:41:05,906|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:2, ver:4} [
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
]
2021-04-08 14:41:05,906|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.event-5||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.139.198]:5701 - be38e4e4-4184-46f0-a0ee-d3f8b47313a1, type=removed, members=[Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this, Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8]}
2021-04-08 14:41:05,907|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Mastership is claimed with: MembersView{version=4, members=[MemberInfo{address=[10.242.8.135]:5701, uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, liteMember=false, memberListJoinVersion=2}, MemberInfo{address=[10.242.254.9]:5701, uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, liteMember=false, memberListJoinVersion=3}]}
2021-04-08 14:41:05,924|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-5||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Committing/rolling-back live transactions of [10.242.139.198]:5701, UUID: be38e4e4-4184-46f0-a0ee-d3f8b47313a1
2021-04-08 14:41:05,928|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-5||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701} is not present in the cluster. It will be auto-removed after 120 seconds.
2021-04-08 14:41:05,959|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Fetching most recent partition table! my version: 814
2021-04-08 14:41:05,965|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Most recent partition table version: 814
2021-04-08 14:41:06,094|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Repartitioning cluster data. Migration tasks count: 180
2021-04-08 14:41:08,212|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] All migration tasks have been completed. (repartitionTime=Thu Apr 08 14:41:06 GMT 2021, plannedMigrations=180, completedMigrations=180, remainingMigrations=0, totalCompletedMigrations=180, elapsedMigrationTime=1084ms, totalElapsedMigrationTime=1084ms)
2021-04-08 14:41:10,875|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:34641 and /10.242.139.198:5701
2021-04-08 14:43:06,865|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701} will be removed from [CPGroupId{name='METADATA', seed=0, groupId=0}]
2021-04-08 14:43:06,915|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: UPDATING_GROUP_MEMBER_LIST
2021-04-08 14:43:06,915|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:2, term:1, logIndex:7} [
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} - LEADER
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
]
2021-04-08 14:43:06,917|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-08 14:43:06,924|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=be38e4e4-4184-46f0-a0ee-d3f8b47313a1, address=[10.242.139.198]:5701} is removed from CP Subsystem.
2021-04-08 14:44:01,893|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.InvocationMonitorThread||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Invocations:2 timeouts:1 backup-timeouts:0
2021-04-08 14:46:01,893|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.InvocationMonitorThread||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Invocations:2 timeouts:1 backup-timeouts:0
2021-04-08 14:47:00,380|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.12.155:42595
2021-04-08 14:47:06,377|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:3, ver:5} [
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991
]
2021-04-08 14:47:06,378|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.event-5||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991, type=added, members=[Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this, Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8, Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991]}
2021-04-08 14:47:06,638|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Repartitioning cluster data. Migration tasks count: 271
2021-04-08 14:47:07,425|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Added new CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701}. New active CP members list: [CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}, CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701}, CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701}]
2021-04-08 14:47:07,905|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: UPDATING_GROUP_MEMBER_LIST
2021-04-08 14:47:07,905|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:1, logIndex:10} [
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} - LEADER
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701}
]
2021-04-08 14:47:07,911|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-08 14:47:07,914|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Rebalancing is completed.
2021-04-08 14:47:11,083|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] All migration tasks have been completed. (repartitionTime=Thu Apr 08 14:47:06 GMT 2021, plannedMigrations=271, completedMigrations=271, remainingMigrations=0, totalCompletedMigrations=451, elapsedMigrationTime=3438ms, totalElapsedMigrationTime=4523ms)
2021-04-08 14:48:01,893|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.InvocationMonitorThread||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Invocations:2 timeouts:1 backup-timeouts:0
2021-04-08 15:03:17,306|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Not updating members because caller: [10.242.139.198]:5701 is not known master: [10.242.8.135]:5701
2021-04-08 15:03:17,856|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=536 sent from [10.242.139.198]:5701
2021-04-08 15:03:17,947|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-1||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=518 sent from [10.242.139.198]:5701
2021-04-08 15:03:17,947|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=527 sent from [10.242.139.198]:5701
2021-04-08 15:03:18,446|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=3, /10.242.8.135:34641->/10.242.139.198:5701, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:03:18,813|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.139.198:50013
2021-04-08 15:03:19,112|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=5, /10.242.8.135:5701->/10.242.139.198:50013, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:03:19,115|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:19,175|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:41905 and /10.242.139.198:5701
2021-04-08 15:03:19,320|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=6, /10.242.8.135:41905->/10.242.139.198:5701, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:03:19,378|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:19,504|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:43749 and /10.242.139.198:5701
2021-04-08 15:03:19,506|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] This is the master node and received partition table update from [10.242.139.198]:5701. Ignoring incoming state!
2021-04-08 15:03:19,506|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.generic-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] This is the master node and received partition table update from [10.242.139.198]:5701. Ignoring incoming state!
2021-04-08 15:03:19,811|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.139.198]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:03:20,136|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.generic-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] This is the master node and received partition table update from [10.242.139.198]:5701. Ignoring incoming state!
2021-04-08 15:03:20,137|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] This is the master node and received partition table update from [10.242.139.198]:5701. Ignoring incoming state!
2021-04-08 15:03:21,285|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.generic-operation.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] [10.242.139.198]:5701 should merge to us because our data member count is bigger than theirs [3 > 1]
2021-04-08 15:03:21,409|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=7, /10.242.8.135:43749->/10.242.139.198:5701, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:03:21,760|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.139.198:52261
2021-04-08 15:03:22,472|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.139.198]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:03:24,755|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.139.198]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:03:27,348|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.139.198]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:03:27,826|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.generic-operation.thread-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:4, ver:6} [
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991
Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77
]
2021-04-08 15:03:27,826|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.event-5||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77, type=added, members=[Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this, Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8, Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991, Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77]}
2021-04-08 15:03:28,020|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Repartitioning cluster data. Migration tasks count: 271
2021-04-08 15:03:28,875|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.priority-generic-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Shutdown request of Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77 is handled
2021-04-08 15:03:28,887|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Repartitioning cluster data. Migration tasks count: 64
2021-04-08 15:03:29,743|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Rejecting PreVoteRequest{candidate=RaftEndpoint{uuid='be38e4e4-4184-46f0-a0ee-d3f8b47313a1'}, nextTerm=2, lastLogTerm=1, lastLogIndex=4} since received append entries recently.
2021-04-08 15:03:30,800|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=8, /10.242.8.135:5701->/10.242.139.198:52261, qualifier=null, endpoint=[10.242.139.198]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:03:30,805|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-8||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:30,807|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-8||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:30,884|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:30,885|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:30,908|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:30,909|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:30,986|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:30,988|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:31,010|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:31,011|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:31,088|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:31,090|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:31,111|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.139.198:5701, timeout: 10000, bind-any: true
2021-04-08 15:03:31,112|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Could not connect to: /10.242.139.198:5701. Reason: SocketException[Connection refused to address /10.242.139.198:5701]
2021-04-08 15:03:31,113|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Removing connection to endpoint [10.242.139.198]:5701 Cause => java.net.SocketException {Connection refused to address /10.242.139.198:5701}, Error-Count: 5
2021-04-08 15:03:31,114|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Removing Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77
2021-04-08 15:03:31,115|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Committing/rolling-back live transactions of [10.242.139.198]:5701, UUID: 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77
2021-04-08 15:03:31,116|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:3, ver:7} [
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991
]
2021-04-08 15:03:31,116|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.event-5||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.139.198]:5701 - 41ef9d7e-9c8a-40f3-b62a-57e4d9e8bb77, type=removed, members=[Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this, Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8, Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991]}
2021-04-08 15:03:31,120|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Partition balance is ok, no need to repartition.
2021-04-08 15:03:32,120|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] All migration tasks have been completed. (repartitionTime=Thu Apr 08 15:03:31 GMT 2021, plannedMigrations=0, completedMigrations=0, remainingMigrations=0, totalCompletedMigrations=579, elapsedMigrationTime=0ms, totalElapsedMigrationTime=7258ms)
2021-04-08 15:04:41,929|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Granted pre-vote for PreVoteRequest{candidate=RaftEndpoint{uuid='a67e71d4-6b58-4234-bda5-f66d55cfa463'}, nextTerm=2, lastLogTerm=1, lastLogIndex=12}
2021-04-08 15:04:41,936|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Moving to new term: 2 from current term: 1 after VoteRequest{candidate=RaftEndpoint{uuid='a67e71d4-6b58-4234-bda5-f66d55cfa463'}, term=2, lastLogTerm=1, lastLogIndex=12, disruptive=false}
2021-04-08 15:04:41,937|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:2, logIndex:10} [
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701}
]
2021-04-08 15:04:41,937|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Granted vote for VoteRequest{candidate=RaftEndpoint{uuid='a67e71d4-6b58-4234-bda5-f66d55cfa463'}, term=2, lastLogTerm=1, lastLogIndex=12, disruptive=false}
2021-04-08 15:04:41,946|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Setting leader: RaftEndpoint{uuid='a67e71d4-6b58-4234-bda5-f66d55cfa463'}
2021-04-08 15:04:41,946|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:3, term:2, logIndex:10} [
CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701}
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701} - LEADER
]
2021-04-08 15:13:50,902|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-3||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Suspecting Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8 because it has not sent any heartbeats since 2021-04-08 15:12:42.272. Now: 2021-04-08 15:13:46.165, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-08 15:13:50,904|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-3||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=2, /10.242.8.135:5701->/10.242.254.9:54955, qualifier=null, endpoint=[10.242.254.9]:5701, alive=false, connectionType=MEMBER] closed. Reason: Suspecting Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8 because it has not sent any heartbeats since 2021-04-08 15:12:42.272. Now: 2021-04-08 15:13:46.165, heartbeat timeout: 60000 ms, suspicion level: 1.00
2021-04-08 15:13:50,905|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-3||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Removing Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8
2021-04-08 15:13:50,914|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-3||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
Members {size:2, ver:8} [
Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this
Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991
]
2021-04-08 15:13:50,914|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-7||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Committing/rolling-back live transactions of [10.242.254.9]:5701, UUID: fdc474b9-9dda-48d7-972e-1c0311e7eac8
2021-04-08 15:13:50,915|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.event-5||||INFO||||||| MembershipEvent: MembershipEvent {member=Member [10.242.254.9]:5701 - fdc474b9-9dda-48d7-972e-1c0311e7eac8, type=removed, members=[Member [10.242.8.135]:5701 - 2404658c-3b7d-4eee-a10d-e0db3a562b8d this, Member [10.242.12.155]:5701 - 4cb66889-f401-4c38-8c85-d1481ecfa991]}
2021-04-08 15:13:50,917|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-7||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} is not present in the cluster. It will be auto-removed after 120 seconds.
2021-04-08 15:13:50,925|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-8||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.254.9:5701, timeout: 10000, bind-any: true
2021-04-08 15:13:50,947|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Repartitioning cluster data. Migration tasks count: 180
2021-04-08 15:13:52,778|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.migration||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] All migration tasks have been completed. (repartitionTime=Thu Apr 08 15:13:50 GMT 2021, plannedMigrations=180, completedMigrations=180, remainingMigrations=0, totalCompletedMigrations=759, elapsedMigrationTime=828ms, totalElapsedMigrationTime=8086ms)
2021-04-08 15:14:23,282|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:56217 and /10.242.254.9:5701
2021-04-08 15:14:23,327|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for call timeout response with callId=1933 sent from [10.242.254.9]:5701
2021-04-08 15:14:24,862|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-1||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=1939 sent from [10.242.254.9]:5701
2021-04-08 15:14:24,900|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=1941 sent from [10.242.254.9]:5701
2021-04-08 15:14:26,038|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=9, /10.242.8.135:56217->/10.242.254.9:5701, qualifier=null, endpoint=[10.242.254.9]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:14:33,584|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.254.9:44059
2021-04-08 15:14:33,592|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.cached.thread-9||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connecting to /10.242.254.9:5701, timeout: 10000, bind-any: true
2021-04-08 15:14:35,186|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:53949 and /10.242.254.9:5701
2021-04-08 15:14:35,636|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=11, /10.242.8.135:53949->/10.242.254.9:5701, qualifier=null, endpoint=[10.242.254.9]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:15:51,857|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} will be removed from [CPGroupId{name='METADATA', seed=0, groupId=0}]
2021-04-08 15:15:51,942|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: UPDATING_GROUP_MEMBER_LIST
2021-04-08 15:15:51,942|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0]
CP Group Members {groupId: METADATA(0), size:2, term:2, logIndex:16} [
CPMember{uuid=2404658c-3b7d-4eee-a10d-e0db3a562b8d, address=[10.242.8.135]:5701} - FOLLOWER this
CPMember{uuid=a67e71d4-6b58-4234-bda5-f66d55cfa463, address=[10.242.12.155]:5701} - LEADER
]
2021-04-08 15:15:51,944|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Status is set to: ACTIVE
2021-04-08 15:15:51,947|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] CPMember{uuid=fdc474b9-9dda-48d7-972e-1c0311e7eac8, address=[10.242.254.9]:5701} is removed from CP Subsystem.
2021-04-08 15:18:01,893|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.InvocationMonitorThread||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Invocations:2 timeouts:1 backup-timeouts:0
2021-04-08 15:18:47,467|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Initialized new cluster connection between /10.242.8.135:5701 and /10.242.254.9:42917
2021-04-08 15:18:47,743|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.response-1||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] No Invocation found for normal response with callId=2906 sent from [10.242.254.9]:5701
2021-04-08 15:18:48,683|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:18:51,096|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:18:53,775|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:18:56,240|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:18:58,299|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:01,207|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:03,397|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:06,147|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:09,085|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:11,799|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:14,337|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:16,468|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:18,902|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:21,331|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.partition-operation.thread-0||||WARN||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] com.hazelcast.spi.exception.CallerNotMemberException: Not Member! this: [10.242.8.135]:5701, caller: [10.242.254.9]:5701, partitionId: 0, operation: com.hazelcast.cp.internal.operation.integration.PreVoteRequestOp, service: hz:core:raft
2021-04-08 15:19:21,977|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-1||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=12, /10.242.8.135:5701->/10.242.254.9:42917, qualifier=null, endpoint=[10.242.254.9]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side
2021-04-08 15:19:21,977|||hz.dev-cds-blueprints-processor-568978cc79-jlnqv.IO.thread-in-2||||INFO||||||| [10.242.8.135]:5701 [cds-cluster] [4.0] Connection[id=10, /10.242.8.135:5701->/10.242.254.9:44059, qualifier=null, endpoint=[10.242.254.9]:5701, alive=false, connectionType=MEMBER] closed. Reason: Connection closed by the other side