hazelcast / hazelcast

Hazelcast is a unified real-time data platform combining stream processing with a fast data store, allowing customers to act instantly on data-in-motion for real-time insights.
https://www.hazelcast.com
Other
5.98k stars 1.81k forks source link

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

Open Patras3 opened 1 month ago

Patras3 commented 1 month ago

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/

olukas commented 1 month ago

Failed again on master with:

AleksPeychev commented 3 weeks ago

Failed again on master

sumnerib commented 1 week ago

Failed again on master with

Patras3 commented 6 days ago

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/