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.internal.util.phonehome.ClientRoutingModePhoneHomeTest.testMultipleClientsRemoved #26342

Open
Patras3 opened this issue May 23, 2024 · 4 comments
Open

com.hazelcast.internal.util.phonehome.ClientRoutingModePhoneHomeTest.testMultipleClientsRemoved #26342

Patras3 opened this issue May 23, 2024 · 4 comments
Labels
Source: Internal PR or issue was opened by an employee Team: Client Team: Core Type: Test-Failure
Milestone
5.5.0

Comments

@Patras3
Copy link
Contributor

Patras3 commented May 23, 2024

master (commit cfe5db342553a5f53777627960aa723db29c5169)

Failed on CorrettoJDK17: https://jenkins.hazelcast.com/job/Hazelcast-master-CorrettoJDK17-arm64/17/testReport/com.hazelcast.internal.util.phonehome/ClientRoutingModePhoneHomeTest/testMultipleClientsRemoved/

Stacktrace: 
org.opentest4j.AssertionFailedError: 

expected: "0"
 but was: "1"
	at jdk.internal.reflect.GeneratedConstructorAccessor345.newInstance(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.base/java.lang.reflect.Constructor.newInstanceWithCaller(Constructor.java:499)
	at com.hazelcast.internal.util.phonehome.ClientRoutingModePhoneHomeTest.testMultipleClientsRemoved(ClientRoutingModePhoneHomeTest.java:120)
	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
	at com.hazelcast.test.FailOnTimeoutStatement$CallableStatement.call(FailOnTimeoutStatement.java:115)
	at com.hazelcast.test.FailOnTimeoutStatement$CallableStatement.call(FailOnTimeoutStatement.java:107)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.lang.Thread.run(Thread.java:840)
Standard output: 
Started Running Test: testMultipleClientsRemoved
Started Running Test: testMultipleClientsRegistered
21:40:55,941  INFO |testMultipleClientsRegistered| - [MetricsConfigHelper] Thread-44813 - [LOCAL] [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
21:40:55,941  INFO |testMultipleClientsRemoved| - [MetricsConfigHelper] Thread-44812 - [LOCAL] [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1'
21:40:55,941  INFO |testMultipleClientsRemoved| - [logo] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.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

21:40:55,941  INFO |testMultipleClientsRemoved| - [system] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Copyright (c) 2008-2024, Hazelcast, Inc. All Rights Reserved.
21:40:55,941  INFO |testMultipleClientsRegistered| - [logo] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.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

21:40:55,941  INFO |testMultipleClientsRemoved| - [system] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Hazelcast Platform 5.5.0-SNAPSHOT (20240522) starting at [127.0.0.1]:5014
21:40:55,941  INFO |testMultipleClientsRegistered| - [system] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Copyright (c) 2008-2024, Hazelcast, Inc. All Rights Reserved.
21:40:55,941  INFO |testMultipleClientsRemoved| - [system] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Cluster name: testMultipleClientsRemoved
21:40:55,941  INFO |testMultipleClientsRegistered| - [system] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Hazelcast Platform 5.5.0-SNAPSHOT (20240522) starting at [127.0.0.1]:5012
21:40:55,941  INFO |testMultipleClientsRemoved| - [system] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Integrity Checker is disabled. Fail-fast on corrupted executables will not be performed. For more information, see the documentation for Integrity Checker.
21:40:55,941  INFO |testMultipleClientsRegistered| - [system] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Cluster name: testMultipleClientsRegistered
21:40:55,941  INFO |testMultipleClientsRemoved| - [system] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] The Jet engine is disabled.
To enable the Jet engine on the members, do one of the following:
  - Change member config using Java API: config.getJetConfig().setEnabled(true)
  - Change XML/YAML configuration property: Set hazelcast.jet.enabled to true
  - Add system property: -Dhz.jet.enabled=true (for Hazelcast embedded, works only when loading config via Config.load)
  - Add environment variable: HZ_JET_ENABLED=true (recommended when running container image. For Hazelcast embedded, works only when loading config via Config.load)
21:40:55,941  INFO |testMultipleClientsRegistered| - [system] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Integrity Checker is disabled. Fail-fast on corrupted executables will not be performed. For more information, see the documentation for Integrity Checker.
21:40:55,941  INFO |testMultipleClientsRegistered| - [system] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] The Jet engine is disabled.
To enable the Jet engine on the members, do one of the following:
  - Change member config using Java API: config.getJetConfig().setEnabled(true)
  - Change XML/YAML configuration property: Set hazelcast.jet.enabled to true
  - Add system property: -Dhz.jet.enabled=true (for Hazelcast embedded, works only when loading config via Config.load)
  - Add environment variable: HZ_JET_ENABLED=true (recommended when running container image. For Hazelcast embedded, works only when loading config via Config.load)
21:40:55,943  INFO |testMultipleClientsRemoved| - [MetricsConfigHelper] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
21:40:55,943  INFO |testMultipleClientsRegistered| - [MetricsConfigHelper] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled
21:40:55,967  INFO |testMultipleClientsRegistered| - [Node] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Using TCP/IP discovery
21:40:55,967  INFO |testMultipleClientsRemoved| - [Node] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Using TCP/IP discovery
21:40:55,974  INFO |testMultipleClientsRegistered| - [Diagnostics] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:55,974  INFO |testMultipleClientsRegistered| - [LifecycleService] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] [127.0.0.1]:5012 is STARTING
21:40:55,991  INFO |testMultipleClientsRegistered| - [ClusterService] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] 

Members {size:1, ver:1} [
	Member [127.0.0.1]:5012 - 59d6f7ca-569d-4eec-ab65-e6f5b65689d0 this
]

21:40:55,992  INFO |testMultipleClientsRegistered| - [LifecycleService] Thread-44813 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] [127.0.0.1]:5012 is STARTED
21:40:55,993  INFO |testMultipleClientsRemoved| - [Diagnostics] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:55,993  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] [127.0.0.1]:5014 is STARTING
21:40:55,997  INFO |testMultipleClientsRemoved| - [ClusterService] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] 

Members {size:1, ver:1} [
	Member [127.0.0.1]:5014 - d74159c9-28a0-45f5-aec3-a7388ca2d432 this
]

21:40:55,997  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] [127.0.0.1]:5014 is STARTED
21:40:56,000  INFO |testMultipleClientsRemoved| - [ClientInvocationService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,001  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,001  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,002  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Trying to connect to cluster: testMultipleClientsRemoved
21:40:56,002  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Trying to connect to [127.0.0.1]:5014
21:40:56,004 DEBUG |testMultipleClientsRemoved| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Processing authentication with clientUuid a8704dde-37ad-44d0-8315-e30d31ebd7a3 and clientName hz.client_4401
21:40:56,004  INFO |testMultipleClientsRemoved| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Received auth from Connection[id=1, /127.0.0.1:5014->/127.0.0.1:41921, qualifier=null, endpoint=[127.0.0.1]:41921, remoteUuid=a8704dde-37ad-44d0-8315-e30d31ebd7a3, alive=true, connectionType=JVM, planeIndex=-1], successfully authenticated, clientUuid: a8704dde-37ad-44d0-8315-e30d31ebd7a3, client name: hz.client_4401, client version: 5.5.0-SNAPSHOT
21:40:56,006  INFO |testMultipleClientsRegistered| - [ClientInvocationService] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,006  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,011  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,012  INFO |testMultipleClientsRemoved| - [ClientClusterService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] 

Members [1] {
	Member [127.0.0.1]:5014 - d74159c9-28a0-45f5-aec3-a7388ca2d432
}

21:40:56,012  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_CONNECTED
21:40:56,012  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Authenticated with server [127.0.0.1]:5014:d74159c9-28a0-45f5-aec3-a7388ca2d432, server version: 5.5.0-SNAPSHOT, local address: /127.0.0.1:41921
21:40:56,013  INFO |testMultipleClientsRemoved| - [Diagnostics] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:56,017  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to cluster: testMultipleClientsRegistered
21:40:56,017  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to [127.0.0.1]:5012
21:40:56,018 DEBUG |testMultipleClientsRemoved| - [ClientListenerService] hz.client_4401.event-40477 - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] No eventHandler for callId: 0, event: ClientMessage{connection=ClientConnection{alive=true, connectionId=1, channel=NioChannel{/127.0.0.1:41921->/127.0.0.1:5014}, remoteAddress=[127.0.0.1]:5014, remoteUuid=d74159c9-28a0-45f5-aec3-a7388ca2d432, clusterUuid=95d8f4a9-7510-46f5-ae55-a02c95f5012a, lastReadTime=2024-05-22 21:40:56.014, lastWriteTime=2024-05-22 21:40:56.017, closedTime=never}, length=38, operation=null, isRetryable=false, correlationId=0, messageType=304, isEvent=true, isfragmented=false}
21:40:56,018 DEBUG |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Processing authentication with clientUuid add500de-8c85-4346-8cce-f1905939f83f and clientName hz.client_4402
21:40:56,018  INFO |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Received auth from Connection[id=1, /127.0.0.1:5012->/127.0.0.1:45085, qualifier=null, endpoint=[127.0.0.1]:45085, remoteUuid=add500de-8c85-4346-8cce-f1905939f83f, alive=true, connectionType=JVM, planeIndex=-1], successfully authenticated, clientUuid: add500de-8c85-4346-8cce-f1905939f83f, client name: hz.client_4402, client version: 5.5.0-SNAPSHOT
21:40:56,019  INFO |testMultipleClientsRegistered| - [ClientClusterService] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] 

Members [1] {
	Member [127.0.0.1]:5012 - 59d6f7ca-569d-4eec-ab65-e6f5b65689d0
}

21:40:56,019  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_CONNECTED
21:40:56,019  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Authenticated with server [127.0.0.1]:5012:59d6f7ca-569d-4eec-ab65-e6f5b65689d0, server version: 5.5.0-SNAPSHOT, local address: /127.0.0.1:45085
21:40:56,019  INFO |testMultipleClientsRegistered| - [Diagnostics] testMultipleClientsRegistered - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:56,020  INFO |testMultipleClientsRemoved| - [ClientStatisticsService] testMultipleClientsRemoved - Client statistics is enabled with period 5 seconds.
21:40:56,022  INFO |testMultipleClientsRemoved| - [ClientInvocationService] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,023  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,025 DEBUG |testMultipleClientsRegistered| - [ClientListenerService] hz.client_4402.event-40483 - hz.client_4402 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] No eventHandler for callId: 0, event: ClientMessage{connection=ClientConnection{alive=true, connectionId=1, channel=NioChannel{/127.0.0.1:45085->/127.0.0.1:5012}, remoteAddress=[127.0.0.1]:5012, remoteUuid=59d6f7ca-569d-4eec-ab65-e6f5b65689d0, clusterUuid=d29e8cbb-dd89-41a3-a109-299006723a1d, lastReadTime=2024-05-22 21:40:56.024, lastWriteTime=2024-05-22 21:40:56.020, closedTime=never}, length=38, operation=null, isRetryable=false, correlationId=0, messageType=304, isEvent=true, isfragmented=false}
21:40:56,025  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,028  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Trying to connect to cluster: testMultipleClientsRemoved
21:40:56,028  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Trying to connect to [127.0.0.1]:5014
21:40:56,031 DEBUG |testMultipleClientsRemoved| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Processing authentication with clientUuid e609dd9c-6c25-4d73-bbb1-f715e2080484 and clientName hz.client_4403
21:40:56,031  INFO |testMultipleClientsRemoved| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Received auth from Connection[id=2, /127.0.0.1:5014->/127.0.0.1:33997, qualifier=null, endpoint=[127.0.0.1]:33997, remoteUuid=e609dd9c-6c25-4d73-bbb1-f715e2080484, alive=true, connectionType=JVM, planeIndex=-1], successfully authenticated, clientUuid: e609dd9c-6c25-4d73-bbb1-f715e2080484, client name: hz.client_4403, client version: 5.5.0-SNAPSHOT
21:40:56,031  INFO |testMultipleClientsRegistered| - [ClientStatisticsService] testMultipleClientsRegistered - Client statistics is enabled with period 5 seconds.
21:40:56,033  INFO |testMultipleClientsRegistered| - [ClientInvocationService] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,033  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,034  INFO |testMultipleClientsRemoved| - [ClientClusterService] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] 

Members [1] {
	Member [127.0.0.1]:5014 - d74159c9-28a0-45f5-aec3-a7388ca2d432
}

21:40:56,034  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_CONNECTED
21:40:56,034  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Authenticated with server [127.0.0.1]:5014:d74159c9-28a0-45f5-aec3-a7388ca2d432, server version: 5.5.0-SNAPSHOT, local address: /127.0.0.1:33997
21:40:56,042  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,043  INFO |testMultipleClientsRemoved| - [Diagnostics] testMultipleClientsRemoved - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:56,044  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to cluster: testMultipleClientsRegistered
21:40:56,044  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to [127.0.0.1]:5012
21:40:56,046 DEBUG |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Processing authentication with clientUuid 9cf2b907-0e7f-4d9f-b381-dbe3e0c5f8ed and clientName hz.client_4404
21:40:56,046  INFO |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Received auth from Connection[id=2, /127.0.0.1:5012->/127.0.0.1:58645, qualifier=null, endpoint=[127.0.0.1]:58645, remoteUuid=9cf2b907-0e7f-4d9f-b381-dbe3e0c5f8ed, alive=true, connectionType=JVM, planeIndex=-1], successfully authenticated, clientUuid: 9cf2b907-0e7f-4d9f-b381-dbe3e0c5f8ed, client name: hz.client_4404, client version: 5.5.0-SNAPSHOT
21:40:56,046  INFO |testMultipleClientsRemoved| - [ClientStatisticsService] testMultipleClientsRemoved - Client statistics is enabled with period 5 seconds.
21:40:56,047  INFO |testMultipleClientsRegistered| - [ClientClusterService] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] 

Members [1] {
	Member [127.0.0.1]:5012 - 59d6f7ca-569d-4eec-ab65-e6f5b65689d0
}

21:40:56,048  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_CONNECTED
21:40:56,048  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Authenticated with server [127.0.0.1]:5012:59d6f7ca-569d-4eec-ab65-e6f5b65689d0, server version: 5.5.0-SNAPSHOT, local address: /127.0.0.1:58645
21:40:56,049  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is SHUTTING_DOWN
21:40:56,049  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5014:d74159c9-28a0-45f5-aec3-a7388ca2d432, connection: ClientConnection{alive=false, connectionId=1, channel=NioChannel{/127.0.0.1:41921->/127.0.0.1:5014}, remoteAddress=[127.0.0.1]:5014, remoteUuid=d74159c9-28a0-45f5-aec3-a7388ca2d432, clusterUuid=95d8f4a9-7510-46f5-ae55-a02c95f5012a, lastReadTime=2024-05-22 21:40:56.025, lastWriteTime=2024-05-22 21:40:56.024, closedTime=2024-05-22 21:40:56.049}
21:40:56,049  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_DISCONNECTED
21:40:56,049  INFO |testMultipleClientsRemoved| - [TcpServerConnection] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.IO.thread-in-0 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Connection[id=1, /127.0.0.1:5014->/127.0.0.1:41921, qualifier=null, endpoint=[127.0.0.1]:41921, remoteUuid=a8704dde-37ad-44d0-8315-e30d31ebd7a3, alive=false, connectionType=JVM, planeIndex=-1] closed. Reason: Connection closed by the other side
21:40:56,050  INFO |testMultipleClientsRemoved| - [LifecycleService] testMultipleClientsRemoved - hz.client_4401 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is SHUTDOWN
21:40:56,053 DEBUG |testMultipleClientsRemoved| - [ClientListenerService] hz.client_4403.event-40488 - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] No eventHandler for callId: 0, event: ClientMessage{connection=ClientConnection{alive=true, connectionId=1, channel=NioChannel{/127.0.0.1:33997->/127.0.0.1:5014}, remoteAddress=[127.0.0.1]:5014, remoteUuid=d74159c9-28a0-45f5-aec3-a7388ca2d432, clusterUuid=95d8f4a9-7510-46f5-ae55-a02c95f5012a, lastReadTime=2024-05-22 21:40:56.051, lastWriteTime=2024-05-22 21:40:56.049, closedTime=never}, length=38, operation=null, isRetryable=false, correlationId=0, messageType=304, isEvent=true, isfragmented=false}
21:40:56,058  INFO |testMultipleClientsRegistered| - [Diagnostics] testMultipleClientsRegistered - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:56,498  INFO |testMultipleClientsRegistered| - [ClientStatisticsService] testMultipleClientsRegistered - Client statistics is enabled with period 5 seconds.
21:40:56,499  INFO |testMultipleClientsRemoved| - [ClientEndpointManager] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.event-40469 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Destroying ClientEndpoint{connection=Connection[id=1, /127.0.0.1:5014->/127.0.0.1:41921, qualifier=null, endpoint=[127.0.0.1]:41921, remoteUuid=a8704dde-37ad-44d0-8315-e30d31ebd7a3, alive=false, connectionType=JVM, planeIndex=-1], clientUuid=a8704dde-37ad-44d0-8315-e30d31ebd7a3, clientName=hz.client_4401, authenticated=true, clientVersion=5.5.0-SNAPSHOT, creationTime=1716414056004, latest clientAttributes=lastStatisticsCollectionTime=1716414056020,enterprise=false,clientType=JVM,clientVersion=5.5.0-SNAPSHOT,clusterConnectionTimestamp=1716414056003,clientAddress=127.0.0.1,clientName=hz.client_4401,credentials.principal=null,os.committedVirtualMemorySize=14545498112,os.freePhysicalMemorySize=390668288,os.freeSwapSpaceSize=0,os.maxFileDescriptorCount=65535,os.openFileDescriptorCount=431,os.processCpuTime=2064060000000,os.systemLoadAverage=1.01,os.totalPhysicalMemorySize=16373067776,os.totalSwapSpaceSize=0,runtime.availableProcessors=4,runtime.freeMemory=5014101920,runtime.maxMemory=9663676416,runtime.totalMemory=7516192768,runtime.uptime=3518678,runtime.usedMemory=2502090848, labels=[]}
21:40:56,502  INFO |testMultipleClientsRegistered| - [ClientInvocationService] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,503  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,505  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,505 DEBUG |testMultipleClientsRegistered| - [ClientListenerService] hz.client_4404.event-40491 - hz.client_4404 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] No eventHandler for callId: 0, event: ClientMessage{connection=ClientConnection{alive=true, connectionId=1, channel=NioChannel{/127.0.0.1:58645->/127.0.0.1:5012}, remoteAddress=[127.0.0.1]:5012, remoteUuid=59d6f7ca-569d-4eec-ab65-e6f5b65689d0, clusterUuid=d29e8cbb-dd89-41a3-a109-299006723a1d, lastReadTime=2024-05-22 21:40:56.505, lastWriteTime=2024-05-22 21:40:56.505, closedTime=never}, length=38, operation=null, isRetryable=false, correlationId=0, messageType=304, isEvent=true, isfragmented=false}
21:40:56,506  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to cluster: testMultipleClientsRegistered
21:40:56,506  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Trying to connect to [127.0.0.1]:5012
21:40:56,508 DEBUG |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Processing authentication with clientUuid b6056095-97bf-4b8c-8b27-7385f9efae9c and clientName hz.client_4405
21:40:56,508  INFO |testMultipleClientsRegistered| - [AuthenticationMessageTask] hz.ClientRoutingModePhoneHomeTest_sleepy_stonebraker.priority-generic-operation.thread-0 - [127.0.0.1]:5012 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Received auth from Connection[id=3, /127.0.0.1:5012->/127.0.0.1:36985, qualifier=null, endpoint=[127.0.0.1]:36985, remoteUuid=b6056095-97bf-4b8c-8b27-7385f9efae9c, alive=true, connectionType=JVM, planeIndex=-1], successfully authenticated, clientUuid: b6056095-97bf-4b8c-8b27-7385f9efae9c, client name: hz.client_4405, client version: 5.5.0-SNAPSHOT
21:40:56,511  INFO |testMultipleClientsRegistered| - [ClientClusterService] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] 

Members [1] {
	Member [127.0.0.1]:5012 - 59d6f7ca-569d-4eec-ab65-e6f5b65689d0
}

21:40:56,511  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_CONNECTED
21:40:56,511  INFO |testMultipleClientsRegistered| - [ClientConnectionManager] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Authenticated with server [127.0.0.1]:5012:59d6f7ca-569d-4eec-ab65-e6f5b65689d0, server version: 5.5.0-SNAPSHOT, local address: /127.0.0.1:36985
21:40:56,511  INFO |testMultipleClientsRegistered| - [Diagnostics] testMultipleClientsRegistered - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
21:40:56,513  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is SHUTTING_DOWN
21:40:56,514 DEBUG |testMultipleClientsRegistered| - [ClientListenerService] hz.client_4405.event-40496 - hz.client_4405 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] No eventHandler for callId: 0, event: ClientMessage{connection=ClientConnection{alive=true, connectionId=1, channel=NioChannel{/127.0.0.1:36985->/127.0.0.1:5012}, remoteAddress=[127.0.0.1]:5012, remoteUuid=59d6f7ca-569d-4eec-ab65-e6f5b65689d0, clusterUuid=d29e8cbb-dd89-41a3-a109-299006723a1d, lastReadTime=2024-05-22 21:40:56.511, lastWriteTime=2024-05-22 21:40:56.512, closedTime=never}, length=38, operation=null, isRetryable=false, correlationId=0, messageType=304, isEvent=true, isfragmented=false}
21:40:56,514  INFO |testMultipleClientsRemoved| - [TcpServerConnection] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.IO.thread-in-1 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Connection[id=2, /127.0.0.1:5014->/127.0.0.1:33997, qualifier=null, endpoint=[127.0.0.1]:33997, remoteUuid=e609dd9c-6c25-4d73-bbb1-f715e2080484, alive=false, connectionType=JVM, planeIndex=-1] closed. Reason: Connection closed by the other side
21:40:56,514  INFO |testMultipleClientsRemoved| - [ClientEndpointManager] hz.ClientRoutingModePhoneHomeTest_gallant_stonebraker.event-40471 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Destroying ClientEndpoint{connection=Connection[id=2, /127.0.0.1:5014->/127.0.0.1:33997, qualifier=null, endpoint=[127.0.0.1]:33997, remoteUuid=e609dd9c-6c25-4d73-bbb1-f715e2080484, alive=false, connectionType=JVM, planeIndex=-1], clientUuid=e609dd9c-6c25-4d73-bbb1-f715e2080484, clientName=hz.client_4403, authenticated=true, clientVersion=5.5.0-SNAPSHOT, creationTime=1716414056031, latest clientAttributes=lastStatisticsCollectionTime=1716414056043,enterprise=false,clientType=JVM,clientVersion=5.5.0-SNAPSHOT,clusterConnectionTimestamp=1716414056029,clientAddress=127.0.0.1,clientName=hz.client_4403,credentials.principal=null,os.committedVirtualMemorySize=14591455232,os.freePhysicalMemorySize=388698112,os.freeSwapSpaceSize=0,os.maxFileDescriptorCount=65535,os.openFileDescriptorCount=444,os.processCpuTime=2064110000000,os.systemLoadAverage=1.01,os.totalPhysicalMemorySize=16373067776,os.totalSwapSpaceSize=0,runtime.availableProcessors=4,runtime.freeMemory=4993604960,runtime.maxMemory=9663676416,runtime.totalMemory=7516192768,runtime.uptime=3518702,runtime.usedMemory=2522587808, labels=[]}
21:40:56,514  INFO |testMultipleClientsRemoved| - [ClientConnectionManager] Thread-44812 - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5014:d74159c9-28a0-45f5-aec3-a7388ca2d432, connection: ClientConnection{alive=false, connectionId=1, channel=NioChannel{/127.0.0.1:33997->/127.0.0.1:5014}, remoteAddress=[127.0.0.1]:5014, remoteUuid=d74159c9-28a0-45f5-aec3-a7388ca2d432, clusterUuid=95d8f4a9-7510-46f5-ae55-a02c95f5012a, lastReadTime=2024-05-22 21:40:56.051, lastWriteTime=2024-05-22 21:40:56.049, closedTime=2024-05-22 21:40:56.514}
21:40:56,514  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is CLIENT_DISCONNECTED
21:40:56,515  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - hz.client_4403 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is SHUTDOWN
21:40:56,515  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] [127.0.0.1]:5014 is SHUTTING_DOWN
21:40:56,515  WARN |testMultipleClientsRemoved| - [Node] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Terminating forcefully...
21:40:56,515  INFO |testMultipleClientsRemoved| - [Node] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Shutting down connection manager...
21:40:56,516  INFO |testMultipleClientsRemoved| - [Node] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Shutting down node engine...
21:40:56,522  INFO |testMultipleClientsRegistered| - [ClientStatisticsService] testMultipleClientsRegistered - Client statistics is enabled with period 5 seconds.
21:40:56,523  INFO |testMultipleClientsRegistered| - [ClientInvocationService] testMultipleClientsRegistered - hz.client_4406 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] Running with 2 response threads, dynamic=true
21:40:56,524  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4406 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTING
21:40:56,524  INFO |testMultipleClientsRegistered| - [LifecycleService] testMultipleClientsRegistered - hz.client_4406 [testMultipleClientsRegistered] [5.5.0-SNAPSHOT] HazelcastClient 5.5.0-SNAPSHOT (20240522) is STARTED
21:40:56,526  INFO |testMultipleClientsRemoved| - [NodeExtension] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Destroying node NodeExtension.
21:40:56,526  INFO |testMultipleClientsRemoved| - [Node] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] Hazelcast Shutdown is completed in 11 ms.
21:40:56,526  INFO |testMultipleClientsRemoved| - [LifecycleService] Thread-44812 - [127.0.0.1]:5014 [testMultipleClientsRemoved] [5.5.0-SNAPSHOT] [127.0.0.1]:5014 is SHUTDOWN
21:40:56,526  INFO |testMultipleClientsRemoved| - [HazelcastTestSupport] Thread-44812 - Shutting down node factory as @After action
BuildInfo right after testMultipleClientsRemoved(com.hazelcast.internal.util.phonehome.ClientRoutingModePhoneHomeTest): BuildInfo{version='5.5.0-SNAPSHOT', build='20240522', buildNumber=20240522, revision=, enterprise=false, serializationVersion=1}
Hiccups measured while running test 'testMultipleClientsRemoved(com.hazelcast.internal.util.phonehome.ClientRoutingModePhoneHomeTest):'
21:40:55, accumulated pauses: 441 ms, max pause: 435 ms, pauses over 1000 ms: 0


No metrics recorded during the test

Standard output can be found here - https://s3.console.aws.amazon.com/s3/buckets/j-artifacts/Hazelcast-master-CorrettoJDK17-arm64/17/
@Patras3 Patras3 added this to the 5.5.0 milestone May 23, 2024
@olukas
Copy link
Contributor

olukas commented May 28, 2024 •
edited
Loading

Failed again on master with:

@AleksPeychev
Copy link
Contributor

AleksPeychev commented Jun 6, 2024 •
edited
Loading

Failed again on master

@sumnerib
Copy link
Contributor

sumnerib commented Jun 20, 2024 •
edited
Loading

Failed again on master with

@Patras3
Copy link
Contributor Author

Patras3 commented Jun 25, 2024

Failed again on master with Windows - OracleJDK21: https://jenkins.hazelcast.com/job/Hazelcast-master-Windows-OracleJDK21/9/testReport/com.hazelcast.internal.util.phonehome/ClientRoutingModePhoneHomeTest/testMultipleClientsRemoved/

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Source: Internal PR or issue was opened by an employee Team: Client Team: Core Type: Test-Failure
Projects
None yet
Milestone
5.5.0
Development

No branches or pull requests
4 participants
@olukas @sumnerib @Patras3 @AleksPeychev