Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

com.hazelcast.splitbrainprotection.list.ListSplitBrainProtectionWriteTest #18766

Closed
olukas opened this issue May 24, 2021 · 2 comments · Fixed by #19180
Closed

com.hazelcast.splitbrainprotection.list.ListSplitBrainProtectionWriteTest #18766

olukas opened this issue May 24, 2021 · 2 comments · Fixed by #19180
Assignees
@sancar
Labels
Module: IList Source: Internal PR or issue was opened by an employee Team: Core Type: Test-Failure
Milestone
5.0

Comments

@olukas
Copy link
Contributor

olukas commented May 24, 2021

master (commit c3c641f)

Failed on Zing JDK 8: http://jenkins.hazelcast.com/view/Official%20Builds/job/Hazelcast-master-ZingJDK8/300/testReport/com.hazelcast.splitbrainprotection.list/ListSplitBrainProtectionWriteTest/com_hazelcast_splitbrainprotection_list_ListSplitBrainProtectionWriteTest/

Stacktrace:

com.hazelcast.splitbrainprotection.SplitBrainProtectionException: Split brain protection exception: threeNodeSplitBrainProtectionRuleREAD has failed!
	at com.hazelcast.splitbrainprotection.impl.SplitBrainProtectionImpl.newSplitBrainProtectionException(SplitBrainProtectionImpl.java:279)
	at com.hazelcast.splitbrainprotection.impl.SplitBrainProtectionImpl.ensureNoSplitBrain(SplitBrainProtectionImpl.java:274)
	at com.hazelcast.splitbrainprotection.impl.SplitBrainProtectionImpl.ensureNoSplitBrain(SplitBrainProtectionImpl.java:269)
	at com.hazelcast.splitbrainprotection.impl.SplitBrainProtectionServiceImpl.ensureNoSplitBrain(SplitBrainProtectionServiceImpl.java:232)
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.ensureNoSplitBrain(OperationRunnerImpl.java:338)
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:243)
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:469)
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:197)
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:137)
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.executeRun(OperationThread.java:123)
	at com.hazelcast.internal.util.executor.HazelcastManagedThread.run(HazelcastManagedThread.java:102)
	at ------ submitted from ------.()
	at com.hazelcast.internal.util.ExceptionUtil.cloneExceptionWithFixedAsyncStackTrace(ExceptionUtil.java:279)
	at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.returnOrThrowWithGetConventions(InvocationFuture.java:112)
	at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.resolveAndThrowIfException(InvocationFuture.java:100)
	at com.hazelcast.spi.impl.AbstractInvocationFuture.get(AbstractInvocationFuture.java:617)
	at com.hazelcast.collection.impl.queue.QueueProxySupport.invokeAndGet(QueueProxySupport.java:179)
	at com.hazelcast.collection.impl.queue.QueueProxySupport.invokeAndGet(QueueProxySupport.java:172)
	at com.hazelcast.collection.impl.queue.QueueProxySupport.size(QueueProxySupport.java:110)
	at com.hazelcast.collection.impl.queue.QueueProxyImpl.size(QueueProxyImpl.java:43)
	at com.hazelcast.splitbrainprotection.AbstractSplitBrainProtectionTest.initCluster(AbstractSplitBrainProtectionTest.java:247)
	at com.hazelcast.splitbrainprotection.AbstractSplitBrainProtectionTest.initTestEnvironment(AbstractSplitBrainProtectionTest.java:105)
	at com.hazelcast.splitbrainprotection.list.ListSplitBrainProtectionWriteTest.setUp(ListSplitBrainProtectionWriteTest.java:57)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.junit.internal.runners.statements.RunBefores.invokeMethod(RunBefores.java:33)
	at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:24)
	at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.apache.maven.surefire.junitcore.JUnitCore.run(JUnitCore.java:55)
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.createRequestAndRun(JUnitCoreWrapper.java:137)
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.executeEager(JUnitCoreWrapper.java:107)
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:83)
	at org.apache.maven.surefire.junitcore.JUnitCoreWrapper.execute(JUnitCoreWrapper.java:75)
	at org.apache.maven.surefire.junitcore.JUnitCoreProvider.invoke(JUnitCoreProvider.java:158)
	at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)

Standard output:

10:15:57,484  INFO || - [MetricsConfigHelper] main - [LOCAL] [hkzoaqzbqc] [5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
10:15:57,488  INFO || - [system] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 
	o  o   O   o---o o--o o      o-o   O    o-o  o-O-o     o--o  o       O  o-O-o o--o  o-o  o--o  o   o 
	|  |  / \     /  |    |     /     / \  |       |       |   | |      / \   |   |    o   o |   | |\ /| 
	O--O o---o  -O-  O-o  |    O     o---o  o-o    |       O--o  |     o---o  |   O-o  |   | O-Oo  | O | 
	|  | |   |  /    |    |     \    |   |     |   |       |     |     |   |  |   |    o   o |  \  |   | 
	o  o o   o o---o o--o O---o  o-o o   o o--o    o       o     O---o o   o  o   o     o-o  o   o o   o
10:15:57,488  INFO || - [system] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
10:15:57,488  INFO || - [system] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Hazelcast Platform 5.0-SNAPSHOT (20210522 - c3c641f) starting at [127.0.0.1]:5701
10:15:57,488  INFO || - [system] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Cluster name: hkzoaqzbqc
10:15:57,496  INFO || - [MetricsConfigHelper] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
10:15:57,506  WARN || - [CPSubsystem] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
10:15:57,511  INFO || - [JetService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Setting number of cooperative threads and default parallelism to 2
10:15:57,513  INFO || - [Diagnostics] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
10:15:57,513  INFO || - [LifecycleService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5701 is STARTING
10:15:57,514  INFO || - [JetExtension] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled after the cluster version upgrade.
10:15:57,514  INFO || - [ClusterService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:1, ver:1} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
]

10:15:57,514  INFO || - [JetExtension] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled
10:15:57,514  INFO || - [LifecycleService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5701 is STARTED
10:15:57,514  INFO || - [MetricsConfigHelper] main - [LOCAL] [hkzoaqzbqc] [5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
10:15:57,515  INFO || - [HealthMonitor] hz.peaceful_ishizaka.HealthMonitor - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] processors=4, physical.memory.total=29.8G, physical.memory.free=13.6G, swap.space.total=0, swap.space.free=0, heap.memory.used=2.6G, heap.memory.free=716.0M, heap.memory.total=3.3G, heap.memory.max=3.3G, heap.memory.used/total=78.50%, heap.memory.used/max=78.50%, minor.gc.count=0, minor.gc.time=0ms, major.gc.count=0, major.gc.time=0ms, unknown.gc.count=12660, unknown.gc.time=3639353ms, load.process=75.00%, load.system=85.00%, load.systemAverage=3.72, thread.count=155, thread.peakCount=1665, cluster.timeDiff=0, event.q.size=1, executor.q.async.size=0, executor.q.client.size=0, executor.q.client.query.size=0, executor.q.client.blocking.size=0, executor.q.query.size=0, executor.q.scheduled.size=0, executor.q.io.size=0, executor.q.system.size=0, executor.q.operations.size=0, executor.q.priorityOperation.size=0, operations.completed.count=0, executor.q.mapLoad.size=0, executor.q.mapLoadAllKeys.size=0, executor.q.cluster.size=0, executor.q.response.size=0, operations.running.count=0, operations.pending.invocations.percentage=0.00%, operations.pending.invocations.count=0, proxy.count=0, clientEndpoint.count=0, connection.active.count=0, client.connection.count=0, connection.count=0
10:15:57,518  INFO || - [system] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 
	o  o   O   o---o o--o o      o-o   O    o-o  o-O-o     o--o  o       O  o-O-o o--o  o-o  o--o  o   o 
	|  |  / \     /  |    |     /     / \  |       |       |   | |      / \   |   |    o   o |   | |\ /| 
	O--O o---o  -O-  O-o  |    O     o---o  o-o    |       O--o  |     o---o  |   O-o  |   | O-Oo  | O | 
	|  | |   |  /    |    |     \    |   |     |   |       |     |     |   |  |   |    o   o |  \  |   | 
	o  o o   o o---o o--o O---o  o-o o   o o--o    o       o     O---o o   o  o   o     o-o  o   o o   o
10:15:57,518  INFO || - [system] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
10:15:57,518  INFO || - [system] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Hazelcast Platform 5.0-SNAPSHOT (20210522 - c3c641f) starting at [127.0.0.1]:5702
10:15:57,518  INFO || - [system] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Cluster name: hkzoaqzbqc
10:15:57,522 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,522 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,526  INFO || - [MetricsConfigHelper] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
10:15:57,537  WARN || - [CPSubsystem] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
10:15:57,542  INFO || - [JetService] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Setting number of cooperative threads and default parallelism to 2
10:15:57,546  INFO || - [Diagnostics] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
10:15:57,546  INFO || - [LifecycleService] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5702 is STARTING
10:15:57,546  INFO || - [MockServer] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5701, alive=true}
10:15:57,547  INFO || - [MockServer] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5702, alive=true}
10:15:57,548  INFO || - [ClusterService] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:2, ver:2} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
]

10:15:57,622 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,622 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-3 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,648  INFO || - [JetExtension] hz.relaxed_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled after the cluster version upgrade.
10:15:57,648  INFO || - [ClusterService] hz.relaxed_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:2, ver:2} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
]

10:15:57,649  INFO || - [JetExtension] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled
10:15:57,650  INFO || - [LifecycleService] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5702 is STARTED
10:15:57,650  INFO || - [MetricsConfigHelper] main - [LOCAL] [hkzoaqzbqc] [5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
10:15:57,651  INFO || - [HealthMonitor] hz.relaxed_ishizaka.HealthMonitor - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] processors=4, physical.memory.total=29.8G, physical.memory.free=13.6G, swap.space.total=0, swap.space.free=0, heap.memory.used=2.6G, heap.memory.free=696.0M, heap.memory.total=3.3G, heap.memory.max=3.3G, heap.memory.used/total=79.10%, heap.memory.used/max=79.10%, minor.gc.count=0, minor.gc.time=0ms, major.gc.count=0, major.gc.time=0ms, unknown.gc.count=12660, unknown.gc.time=3639353ms, load.process=38.46%, load.system=61.54%, load.systemAverage=3.72, thread.count=184, thread.peakCount=1665, cluster.timeDiff=-1, event.q.size=0, executor.q.async.size=0, executor.q.client.size=0, executor.q.client.query.size=0, executor.q.client.blocking.size=0, executor.q.query.size=0, executor.q.scheduled.size=0, executor.q.io.size=0, executor.q.system.size=0, executor.q.operations.size=0, executor.q.priorityOperation.size=0, operations.completed.count=17, executor.q.mapLoad.size=0, executor.q.mapLoadAllKeys.size=0, executor.q.cluster.size=0, executor.q.response.size=0, operations.running.count=0, operations.pending.invocations.percentage=0.00%, operations.pending.invocations.count=0, proxy.count=0, clientEndpoint.count=0, connection.active.count=0, client.connection.count=0, connection.count=0
10:15:57,654  INFO || - [system] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 
	o  o   O   o---o o--o o      o-o   O    o-o  o-O-o     o--o  o       O  o-O-o o--o  o-o  o--o  o   o 
	|  |  / \     /  |    |     /     / \  |       |       |   | |      / \   |   |    o   o |   | |\ /| 
	O--O o---o  -O-  O-o  |    O     o---o  o-o    |       O--o  |     o---o  |   O-o  |   | O-Oo  | O | 
	|  | |   |  /    |    |     \    |   |     |   |       |     |     |   |  |   |    o   o |  \  |   | 
	o  o o   o o---o o--o O---o  o-o o   o o--o    o       o     O---o o   o  o   o     o-o  o   o o   o
10:15:57,654  INFO || - [system] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
10:15:57,654  INFO || - [system] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Hazelcast Platform 5.0-SNAPSHOT (20210522 - c3c641f) starting at [127.0.0.1]:5703
10:15:57,654  INFO || - [system] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Cluster name: hkzoaqzbqc
10:15:57,663  INFO || - [MetricsConfigHelper] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
10:15:57,673  WARN || - [CPSubsystem] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
10:15:57,679  INFO || - [JetService] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Setting number of cooperative threads and default parallelism to 2
10:15:57,682  INFO || - [Diagnostics] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
10:15:57,682  INFO || - [LifecycleService] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5703 is STARTING
10:15:57,683  INFO || - [MockServer] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5701, alive=true}
10:15:57,683  INFO || - [MockServer] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5703, alive=true}
10:15:57,684  INFO || - [ClusterService] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:3} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
]

10:15:57,685  INFO || - [MockServer] hz.relaxed_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5703, alive=true}
10:15:57,685  INFO || - [ClusterService] hz.relaxed_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:3} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
]

10:15:57,722 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-3 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,722 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-3 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,784  INFO || - [JetExtension] hz.romantic_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled after the cluster version upgrade.
10:15:57,785  INFO || - [ClusterService] hz.romantic_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:3} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 this
]

10:15:57,785  INFO || - [MockServer] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5702, alive=true}
10:15:57,785  INFO || - [JetExtension] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled
10:15:57,785  INFO || - [LifecycleService] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5703 is STARTED
10:15:57,785  INFO || - [MetricsConfigHelper] main - [LOCAL] [hkzoaqzbqc] [5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
10:15:57,789  INFO || - [HealthMonitor] hz.romantic_ishizaka.HealthMonitor - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] processors=4, physical.memory.total=29.8G, physical.memory.free=13.6G, swap.space.total=0, swap.space.free=0, heap.memory.used=2.6G, heap.memory.free=674.0M, heap.memory.total=3.3G, heap.memory.max=3.3G, heap.memory.used/total=79.76%, heap.memory.used/max=79.76%, minor.gc.count=0, minor.gc.time=0ms, major.gc.count=0, major.gc.time=0ms, unknown.gc.count=12660, unknown.gc.time=3639353ms, load.process=40.74%, load.system=62.96%, load.systemAverage=3.72, thread.count=212, thread.peakCount=1665, cluster.timeDiff=-1, event.q.size=0, executor.q.async.size=0, executor.q.client.size=0, executor.q.client.query.size=0, executor.q.client.blocking.size=0, executor.q.query.size=0, executor.q.scheduled.size=0, executor.q.io.size=0, executor.q.system.size=0, executor.q.operations.size=0, executor.q.priorityOperation.size=0, operations.completed.count=14, executor.q.mapLoad.size=0, executor.q.mapLoadAllKeys.size=0, executor.q.cluster.size=0, executor.q.response.size=0, operations.running.count=2, operations.pending.invocations.percentage=0.00%, operations.pending.invocations.count=0, proxy.count=0, clientEndpoint.count=0, connection.active.count=0, client.connection.count=0, connection.count=0
10:15:57,791  INFO || - [system] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] 
	o  o   O   o---o o--o o      o-o   O    o-o  o-O-o     o--o  o       O  o-O-o o--o  o-o  o--o  o   o 
	|  |  / \     /  |    |     /     / \  |       |       |   | |      / \   |   |    o   o |   | |\ /| 
	O--O o---o  -O-  O-o  |    O     o---o  o-o    |       O--o  |     o---o  |   O-o  |   | O-Oo  | O | 
	|  | |   |  /    |    |     \    |   |     |   |       |     |     |   |  |   |    o   o |  \  |   | 
	o  o o   o o---o o--o O---o  o-o o   o o--o    o       o     O---o o   o  o   o     o-o  o   o o   o
10:15:57,791  INFO || - [system] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
10:15:57,791  INFO || - [system] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Hazelcast Platform 5.0-SNAPSHOT (20210522 - c3c641f) starting at [127.0.0.1]:5704
10:15:57,791  INFO || - [system] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Cluster name: hkzoaqzbqc
10:15:57,799  INFO || - [MetricsConfigHelper] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
10:15:57,809  WARN || - [CPSubsystem] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
10:15:57,815  INFO || - [JetService] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Setting number of cooperative threads and default parallelism to 2
10:15:57,818  INFO || - [Diagnostics] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
10:15:57,818  INFO || - [LifecycleService] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5704 is STARTING
10:15:57,819  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5701, alive=true}
10:15:57,819  INFO || - [MockServer] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5704, alive=true}
10:15:57,820  INFO || - [ClusterService] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:4} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
]

10:15:57,821  INFO || - [MockServer] hz.romantic_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5704, alive=true}
10:15:57,821  INFO || - [MockServer] hz.relaxed_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5704, alive=true}
10:15:57,823 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-6 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,823  INFO || - [ClusterService] hz.romantic_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:4} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 this
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
]

10:15:57,823 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,824  INFO || - [ClusterService] hz.relaxed_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:4} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
]

10:15:57,920  INFO || - [JetExtension] hz.happy_ishizaka.generic-operation.thread-0 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled after the cluster version upgrade.
10:15:57,921  INFO || - [ClusterService] hz.happy_ishizaka.generic-operation.thread-0 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:4} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba this
]

10:15:57,921  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5702, alive=true}
10:15:57,921  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5703, alive=true}
10:15:57,921  INFO || - [JetExtension] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled
10:15:57,922  INFO || - [LifecycleService] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5704 is STARTED
10:15:57,922  INFO || - [MetricsConfigHelper] main - [LOCAL] [hkzoaqzbqc] [5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
10:15:57,924 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,924 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-6 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:57,926  INFO || - [system] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] 
	o  o   O   o---o o--o o      o-o   O    o-o  o-O-o     o--o  o       O  o-O-o o--o  o-o  o--o  o   o 
	|  |  / \     /  |    |     /     / \  |       |       |   | |      / \   |   |    o   o |   | |\ /| 
	O--O o---o  -O-  O-o  |    O     o---o  o-o    |       O--o  |     o---o  |   O-o  |   | O-Oo  | O | 
	|  | |   |  /    |    |     \    |   |     |   |       |     |     |   |  |   |    o   o |  \  |   | 
	o  o o   o o---o o--o O---o  o-o o   o o--o    o       o     O---o o   o  o   o     o-o  o   o o   o
10:15:57,926  INFO || - [system] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
10:15:57,926  INFO || - [system] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Hazelcast Platform 5.0-SNAPSHOT (20210522 - c3c641f) starting at [127.0.0.1]:5705
10:15:57,926  INFO || - [system] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Cluster name: hkzoaqzbqc
10:15:57,933  INFO || - [HealthMonitor] hz.happy_ishizaka.HealthMonitor - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] processors=4, physical.memory.total=29.8G, physical.memory.free=13.6G, swap.space.total=0, swap.space.free=0, heap.memory.used=2.6G, heap.memory.free=664.0M, heap.memory.total=3.3G, heap.memory.max=3.3G, heap.memory.used/total=80.06%, heap.memory.used/max=80.06%, minor.gc.count=0, minor.gc.time=0ms, major.gc.count=0, major.gc.time=0ms, unknown.gc.count=12660, unknown.gc.time=3639353ms, load.process=44.23%, load.system=65.38%, load.systemAverage=3.72, thread.count=240, thread.peakCount=1665, cluster.timeDiff=-2, event.q.size=0, executor.q.async.size=0, executor.q.client.size=0, executor.q.client.query.size=0, executor.q.client.blocking.size=0, executor.q.query.size=0, executor.q.scheduled.size=0, executor.q.io.size=0, executor.q.system.size=0, executor.q.operations.size=8, executor.q.priorityOperation.size=6, operations.completed.count=8, executor.q.mapLoad.size=0, executor.q.mapLoadAllKeys.size=0, executor.q.cluster.size=0, executor.q.response.size=0, operations.running.count=0, operations.pending.invocations.percentage=0.00%, operations.pending.invocations.count=0, proxy.count=0, clientEndpoint.count=0, connection.active.count=0, client.connection.count=0, connection.count=0
10:15:57,936  INFO || - [MetricsConfigHelper] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
10:15:57,946  WARN || - [CPSubsystem] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
10:15:57,952  INFO || - [JetService] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Setting number of cooperative threads and default parallelism to 2
10:15:57,956  INFO || - [Diagnostics] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
10:15:57,956  INFO || - [LifecycleService] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5705 is STARTING
10:15:57,956  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5701, alive=true}
10:15:57,957  INFO || - [MockServer] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5705, alive=true}
10:15:57,958  INFO || - [ClusterService] hz.peaceful_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:5, ver:5} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:15:57,959  INFO || - [MockServer] hz.relaxed_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5705, alive=true}
10:15:57,959  INFO || - [ClusterService] hz.relaxed_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:5, ver:5} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:15:57,959  INFO || - [MockServer] hz.romantic_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5705, alive=true}
10:15:57,960  INFO || - [ClusterService] hz.romantic_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:5, ver:5} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 this
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:15:57,960  INFO || - [MockServer] hz.happy_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5705, alive=true}
10:15:57,960  INFO || - [ClusterService] hz.happy_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:5, ver:5} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba this
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:15:58,025 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:58,025 DEBUG || - [JobCoordinationService] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Not starting jobs because partitions are not yet initialized.
10:15:58,058  INFO || - [JetExtension] hz.kind_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled after the cluster version upgrade.
10:15:58,059  INFO || - [ClusterService] hz.kind_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:5, ver:5} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5 this
]

10:15:58,061  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5702, alive=true}
10:15:58,061  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5703, alive=true}
10:15:58,061  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5704, alive=true}
10:15:58,061  INFO || - [JetExtension] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Jet extension is enabled
10:15:58,061  INFO || - [LifecycleService] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] [127.0.0.1]:5705 is STARTED
10:15:58,061  INFO || - [PartitionStateManager] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Initializing cluster partition table arrangement...
10:15:58,064  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size before data initialization for READ is: 0
10:15:58,064  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size before data initialization for WRITE is: 0
10:15:58,065  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size before data initialization for READ_WRITE is: 0
10:15:58,067  INFO || - [HealthMonitor] hz.kind_ishizaka.HealthMonitor - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] processors=4, physical.memory.total=29.8G, physical.memory.free=13.6G, swap.space.total=0, swap.space.free=0, heap.memory.used=2.6G, heap.memory.free=646.0M, heap.memory.total=3.3G, heap.memory.max=3.3G, heap.memory.used/total=80.54%, heap.memory.used/max=80.54%, minor.gc.count=0, minor.gc.time=0ms, major.gc.count=0, major.gc.time=0ms, unknown.gc.count=12660, unknown.gc.time=3639353ms, load.process=44.00%, load.system=68.00%, load.systemAverage=3.72, thread.count=271, thread.peakCount=1665, cluster.timeDiff=-1, event.q.size=0, executor.q.async.size=0, executor.q.client.size=0, executor.q.client.query.size=0, executor.q.client.blocking.size=0, executor.q.query.size=0, executor.q.scheduled.size=0, executor.q.io.size=0, executor.q.system.size=0, executor.q.operations.size=0, executor.q.priorityOperation.size=0, operations.completed.count=21, executor.q.mapLoad.size=0, executor.q.mapLoadAllKeys.size=0, executor.q.cluster.size=0, executor.q.response.size=0, operations.running.count=0, operations.pending.invocations.percentage=0.00%, operations.pending.invocations.count=0, proxy.count=3, clientEndpoint.count=0, connection.active.count=0, client.connection.count=0, connection.count=0
10:15:58,128 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:15:58,129 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-6 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:03,129 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:03,130 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:08,130 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:08,131 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:13,131 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-9 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:13,132 DEBUG || - [JobRepository] hz.peaceful_ishizaka.cached.thread-5 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Job cleanup took 0ms
10:16:15,390  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for READ is: 10000
10:16:15,391  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for WRITE is: 10000
10:16:15,391  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for READ_WRITE is: 10000
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5701, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5704, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5701, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5701, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5701, remoteEndpoint=[127.0.0.1]:5705, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5702, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5704, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5702, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5702, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5702, remoteEndpoint=[127.0.0.1]:5705, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5704, remoteEndpoint=[127.0.0.1]:5703, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5704, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5704, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5703, connection: MockConnection{localEndpoint=[127.0.0.1]:5705, remoteEndpoint=[127.0.0.1]:5703, alive=false}
10:16:15,391  INFO || - [MockServer] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5705, connection: MockConnection{localEndpoint=[127.0.0.1]:5703, remoteEndpoint=[127.0.0.1]:5705, alive=false}
10:16:15,391  WARN || - [MembershipManager] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 is suspected to be dead
10:16:15,391  WARN || - [MembershipManager] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba is suspected to be dead
10:16:15,391  WARN || - [MembershipManager] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e is suspected to be dead
10:16:15,391  WARN || - [MembershipManager] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba is suspected to be dead
10:16:15,391  WARN || - [MembershipManager] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 is suspected to be dead
10:16:15,391  INFO || - [MembershipManager] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Starting mastership claim process...
10:16:15,392  INFO || - [MembershipManager] main - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Local MembersView{version=5, members=[MemberInfo{address=[127.0.0.1]:5701, uuid=b66f62d2-68aa-4258-b40e-52eb8c0cdc05, liteMember=false, memberListJoinVersion=1}, MemberInfo{address=[127.0.0.1]:5702, uuid=bdef9641-3891-4c3e-9749-2b026b344c4e, liteMember=false, memberListJoinVersion=2}, MemberInfo{address=[127.0.0.1]:5703, uuid=2cdf72de-f12d-4144-ba23-407f07213879, liteMember=false, memberListJoinVersion=3}, MemberInfo{address=[127.0.0.1]:5704, uuid=2fdd22e7-8e2b-43fd-8df4-649dbb8921ba, liteMember=false, memberListJoinVersion=4}, MemberInfo{address=[127.0.0.1]:5705, uuid=5e2d527c-49c7-4315-a452-1f8d22d107f5, liteMember=false, memberListJoinVersion=5}]} with suspected members: [Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879, Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05, Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e] and initial addresses to ask: [Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5]
10:16:15,392  INFO || - [MembershipManager] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Removing Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
10:16:15,392  INFO || - [ClusterService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:6} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:16:15,392  WARN || - [MembershipManager] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 is suspected to be dead
10:16:15,392  INFO || - [TransactionManagerService] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5704, UUID: 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
10:16:15,392  WARN || - [MembershipManager] main - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5 is suspected to be dead
10:16:15,392  WARN || - [MembershipManager] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e is suspected to be dead
10:16:15,392  WARN || - [MembershipManager] main - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5 is suspected to be dead
10:16:15,392  WARN || - [MembershipManager] main - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 is suspected to be dead
10:16:15,392  INFO || - [MembershipManager] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Removing Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
10:16:15,392  INFO || - [ClusterService] main - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:7} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05 this
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
]

10:16:15,393  INFO || - [TransactionManagerService] hz.peaceful_ishizaka.cached.thread-4 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5705, UUID: 5e2d527c-49c7-4315-a452-1f8d22d107f5
10:16:15,393  INFO || - [ClusterService] hz.relaxed_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:6} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:16:15,393  INFO || - [TransactionManagerService] hz.romantic_ishizaka.cached.thread-2 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5704, UUID: 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
10:16:15,393  INFO || - [ClusterService] hz.relaxed_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:7} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e this
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
]

10:16:15,394  INFO || - [TransactionManagerService] hz.relaxed_ishizaka.cached.thread-9 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5704, UUID: 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
10:16:15,394  INFO || - [TransactionManagerService] hz.relaxed_ishizaka.cached.thread-9 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5705, UUID: 5e2d527c-49c7-4315-a452-1f8d22d107f5
10:16:15,394  WARN || - [ClusterService] hz.kind_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Mastership of [127.0.0.1]:5704 is accepted. Response: MembersView{version=5, members=[MemberInfo{address=[127.0.0.1]:5704, uuid=2fdd22e7-8e2b-43fd-8df4-649dbb8921ba, liteMember=false, memberListJoinVersion=4}, MemberInfo{address=[127.0.0.1]:5705, uuid=5e2d527c-49c7-4315-a452-1f8d22d107f5, liteMember=false, memberListJoinVersion=5}]}
10:16:15,393  INFO || - [ClusterService] hz.romantic_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:4, ver:6} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 this
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:16:15,395  INFO || - [TransactionManagerService] hz.happy_ishizaka.cached.thread-1 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5701, UUID: b66f62d2-68aa-4258-b40e-52eb8c0cdc05
10:16:15,395  INFO || - [ClusterService] hz.romantic_ishizaka.generic-operation.thread-1 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:3, ver:7} [
	Member [127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	Member [127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
	Member [127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879 this
]

10:16:15,395  INFO || - [TransactionManagerService] hz.happy_ishizaka.cached.thread-1 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5702, UUID: bdef9641-3891-4c3e-9749-2b026b344c4e
10:16:15,395  INFO || - [ClusterService] hz.happy_ishizaka.cached.thread-6 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:2, ver:6} [
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba this
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5
]

10:16:15,395  INFO || - [TransactionManagerService] hz.happy_ishizaka.cached.thread-1 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5703, UUID: 2cdf72de-f12d-4144-ba23-407f07213879
10:16:15,395  INFO || - [MembershipManager] hz.happy_ishizaka.cached.thread-6 - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Mastership is claimed with: MembersView{version=6, members=[MemberInfo{address=[127.0.0.1]:5704, uuid=2fdd22e7-8e2b-43fd-8df4-649dbb8921ba, liteMember=false, memberListJoinVersion=4}, MemberInfo{address=[127.0.0.1]:5705, uuid=5e2d527c-49c7-4315-a452-1f8d22d107f5, liteMember=false, memberListJoinVersion=5}]}
10:16:15,395  INFO || - [TransactionManagerService] hz.kind_ishizaka.cached.thread-2 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5701, UUID: b66f62d2-68aa-4258-b40e-52eb8c0cdc05
10:16:15,395  INFO || - [ClusterService] hz.kind_ishizaka.generic-operation.thread-0 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] 

Members {size:2, ver:6} [
	Member [127.0.0.1]:5704 - 2fdd22e7-8e2b-43fd-8df4-649dbb8921ba
	Member [127.0.0.1]:5705 - 5e2d527c-49c7-4315-a452-1f8d22d107f5 this
]

10:16:15,395  INFO || - [TransactionManagerService] hz.kind_ishizaka.cached.thread-2 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5702, UUID: bdef9641-3891-4c3e-9749-2b026b344c4e
10:16:15,395  INFO || - [TransactionManagerService] hz.romantic_ishizaka.cached.thread-9 - [127.0.0.1]:5703 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5705, UUID: 5e2d527c-49c7-4315-a452-1f8d22d107f5
10:16:15,395  INFO || - [TransactionManagerService] hz.kind_ishizaka.cached.thread-2 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5703, UUID: 2cdf72de-f12d-4144-ba23-407f07213879
10:16:15,397  INFO || - [InternalPartitionService] hz.happy_ishizaka.migration - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Fetching partition tables from cluster to determine the most recent one... Local stamp: -7939033747391728028
10:16:15,397  INFO || - [InternalPartitionService] hz.happy_ishizaka.migration - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Most recent partition table is determined.
10:16:15,397  INFO || - [InternalPartitionService] hz.happy_ishizaka.migration - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Applying the most recent of partition state...
10:16:15,397  WARN || - [InternalPartitionService] hz.happy_ishizaka.migration - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Following unknown addresses are found in partition table sent from master[[127.0.0.1]:5704]. (Probably they have recently joined or left the cluster.) {
	[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	[127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	[127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
}
10:16:15,397  WARN || - [InternalPartitionService] hz.kind_ishizaka.priority-generic-operation.thread-0 - [127.0.0.1]:5705 [hkzoaqzbqc] [5.0-SNAPSHOT] Following unknown addresses are found in partition table sent from master[[127.0.0.1]:5704]. (Probably they have recently joined or left the cluster.) {
	[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879
	[127.0.0.1]:5701 - b66f62d2-68aa-4258-b40e-52eb8c0cdc05
	[127.0.0.1]:5702 - bdef9641-3891-4c3e-9749-2b026b344c4e
}
10:16:15,402  INFO || - [MigrationManager] hz.happy_ishizaka.migration - [127.0.0.1]:5704 [hkzoaqzbqc] [5.0-SNAPSHOT] Repartitioning cluster data. Migration tasks count: 10
10:16:15,405  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 0 for type READ is: 10000
10:16:15,406  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 1 for type READ is: 10000
10:16:15,407  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 2 for type READ is: 10000
10:16:15,407  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 0 for type WRITE is: 10000
10:16:15,407  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 1 for type WRITE is: 10000
10:16:15,407  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 2 for type WRITE is: 10000
10:16:15,413  INFO || - [MigrationManager] hz.peaceful_ishizaka.migration - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Repartitioning cluster data. Migration tasks count: 12
10:16:15,414  WARN || - [MigrationRequestOperation] ForkJoinPool.commonPool-worker-324 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Failure while executing MigrationInfo{uuid=12b401f9-55a8-43f1-9723-65fdcf271638, partitionId=4, source=null, sourceCurrentReplicaIndex=-1, sourceNewReplicaIndex=-1, destination=[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879, destinationCurrentReplicaIndex=4, destinationNewReplicaIndex=1, master=[127.0.0.1]:5701, initialPartitionVersion=9, partitionVersionIncrement=2, status=ACTIVE}
com.hazelcast.internal.partition.PartitionStateVersionMismatchException: Local partition stamp is not equal to master's stamp! Local: 7, Master: 9
	at com.hazelcast.internal.partition.operation.BaseMigrationOperation.verifyPartitionVersion(BaseMigrationOperation.java:138) ~[classes/:?]
	at com.hazelcast.internal.partition.operation.BaseMigrationOperation.beforeRun(BaseMigrationOperation.java:91) ~[classes/:?]
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:247) ~[classes/:?]
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:469) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:197) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:137) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.executeRun(OperationThread.java:123) ~[classes/:?]
	at com.hazelcast.internal.util.executor.HazelcastManagedThread.run(HazelcastManagedThread.java:102) ~[classes/:?]
10:16:15,414  WARN || - [MigrationManager] ForkJoinPool.commonPool-worker-324 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Migration failed: MigrationInfo{uuid=12b401f9-55a8-43f1-9723-65fdcf271638, partitionId=4, source=null, sourceCurrentReplicaIndex=-1, sourceNewReplicaIndex=-1, destination=[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879, destinationCurrentReplicaIndex=4, destinationNewReplicaIndex=1, master=[127.0.0.1]:5701, initialPartitionVersion=9, partitionVersionIncrement=2, status=ACTIVE}
10:16:15,419  WARN || - [MigrationRequestOperation] ForkJoinPool.commonPool-worker-96 - [127.0.0.1]:5702 [hkzoaqzbqc] [5.0-SNAPSHOT] Failure while executing MigrationInfo{uuid=f4da5543-34c4-41f3-b019-469fae6ec2dd, partitionId=3, source=null, sourceCurrentReplicaIndex=-1, sourceNewReplicaIndex=-1, destination=[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879, destinationCurrentReplicaIndex=4, destinationNewReplicaIndex=1, master=[127.0.0.1]:5701, initialPartitionVersion=9, partitionVersionIncrement=2, status=ACTIVE}
com.hazelcast.internal.partition.PartitionStateVersionMismatchException: Local partition stamp is not equal to master's stamp! Local: 7, Master: 9
	at com.hazelcast.internal.partition.operation.BaseMigrationOperation.verifyPartitionVersion(BaseMigrationOperation.java:138) ~[classes/:?]
	at com.hazelcast.internal.partition.operation.BaseMigrationOperation.beforeRun(BaseMigrationOperation.java:91) ~[classes/:?]
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:247) ~[classes/:?]
	at com.hazelcast.spi.impl.operationservice.impl.OperationRunnerImpl.run(OperationRunnerImpl.java:469) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:197) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.process(OperationThread.java:137) ~[classes/:?]
	at com.hazelcast.spi.impl.operationexecutor.impl.OperationThread.executeRun(OperationThread.java:123) ~[classes/:?]
	at com.hazelcast.internal.util.executor.HazelcastManagedThread.run(HazelcastManagedThread.java:102) ~[classes/:?]
10:16:15,430  WARN || - [MigrationManager] ForkJoinPool.commonPool-worker-96 - [127.0.0.1]:5701 [hkzoaqzbqc] [5.0-SNAPSHOT] Migration failed: MigrationInfo{uuid=f4da5543-34c4-41f3-b019-469fae6ec2dd, partitionId=3, source=null, sourceCurrentReplicaIndex=-1, sourceNewReplicaIndex=-1, destination=[127.0.0.1]:5703 - 2cdf72de-f12d-4144-ba23-407f07213879, destinationCurrentReplicaIndex=4, destinationNewReplicaIndex=1, master=[127.0.0.1]:5701, initialPartitionVersion=9, partitionVersionIncrement=2, status=ACTIVE}
10:16:15,437  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 0 for type READ_WRITE is: 10000
10:16:15,437  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 1 for type READ_WRITE is: 10000
10:16:15,437  INFO || - [AbstractSplitBrainProtectionTest] main - Queue size after data initialization for instance 2 for type READ_WRITE is: 10000
Started Running Test: sizeOperation_successful_whenSplitBrainProtectionSize_met[splitBrainProtectionType:READ]
@olukas olukas added this to the 5.0 milestone May 24, 2021
@mmedenjak mmedenjak added the Source: Internal PR or issue was opened by an employee label May 24, 2021
This was referenced Jul 26, 2021
Closed
Closed
@mmedenjak
Copy link
Contributor

All of these seem related:
#18950
#18777
#18766
#18765
#18764

This was referenced Jul 26, 2021
Closed
Closed
@mmedenjak
Copy link
Contributor

Also this - #18930

@mmedenjak mmedenjak mentioned this issue Jul 26, 2021
Closed
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 26, 2021
Fixes SplitBrainTest start issues
a33a1a7 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930
@sancar sancar mentioned this issue Jul 26, 2021
Merged
@sancar sancar self-assigned this Jul 26, 2021
mmedenjak pushed a commit that referenced this issue Jul 26, 2021
Fixes SplitBrainTest start issues (#19180)
8834585 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes #18950
fixes #18777
fixes #18766
fixes #18765
fixes #18764
fixes #18930
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 27, 2021
Fixes SplitBrainTest start issues
427f656 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930

(cherry picked from commit a33a1a7)
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 27, 2021
Fixes SplitBrainTest start issues
d1ca409 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930

(cherry picked from commit a33a1a7)
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 27, 2021
Fixes SplitBrainTest start issues
308aaa0 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930

(cherry picked from commit a33a1a7)
@sancar sancar mentioned this issue Jul 27, 2021
Merged
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 27, 2021
Fixes SplitBrainTest start issues
3a70a65 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930

(cherry picked from commit a33a1a7)
@sancar sancar mentioned this issue Jul 27, 2021
Merged
sancar pushed a commit to sancar/hazelcast that referenced this issue Jul 27, 2021
Fixes SplitBrainTest start issues
3f56245 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes hazelcast#18950
fixes hazelcast#18777
fixes hazelcast#18766
fixes hazelcast#18765
fixes hazelcast#18764
fixes hazelcast#18930

(cherry picked from commit a33a1a7)
@sancar sancar mentioned this issue Jul 27, 2021
Merged
mmedenjak pushed a commit that referenced this issue Jul 28, 2021
Fixes SplitBrainTest start issues (#19189)
2d27fc3 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes #18950
fixes #18777
fixes #18766
fixes #18765
fixes #18764
fixes #18930

(cherry picked from commit a33a1a7)
mmedenjak pushed a commit that referenced this issue Jul 28, 2021
Fixes SplitBrainTest start issues (#19191)
bc678a5 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes #18950
fixes #18777
fixes #18766
fixes #18765
fixes #18764
fixes #18930

(cherry picked from commit a33a1a7)
mmedenjak pushed a commit that referenced this issue Jul 28, 2021
Fixes SplitBrainTest start issues (#19190)
05fad5d 
SplitBrainStatus is set by a single thread at the hazelcast instance
start in an async manner. Therefore, we are not sure that a the
status is set when the test starts.

Adding an eventually check at the start so that we make sure we
have the min-size cluster for all split brain protections before
we actually split the cluster

fixes #18950
fixes #18777
fixes #18766
fixes #18765
fixes #18764
fixes #18930

(cherry picked from commit a33a1a7)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@sancar sancar

Labels
Module: IList Source: Internal PR or issue was opened by an employee Team: Core Type: Test-Failure
Projects
None yet
Milestone
5.0
Development

Successfully merging a pull request may close this issue.

Fixes SplitBrainTest start issues sancar/hazelcast
3 participants
@sancar @olukas @mmedenjak