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
6.09k stars 1.83k forks source link

com.hazelcast.mapstore.mysql.MySQLGenericMapStoreIntegrationTest.testInstanceShutdown #24898

Closed olukas closed 11 months ago

olukas commented 1 year ago

5.3.z (commit f17d3e9509336661ebcea27322253205455916c2)

Failed on ibm-8: https://jenkins.hazelcast.com/job/Hazelcast-5.maintenance-IbmJDK8-nightly/90/testReport/com.hazelcast.mapstore.mysql/MySQLGenericMapStoreIntegrationTest/testInstanceShutdown/

Stacktrace: ``` org.junit.ComparisonFailure: Expecting actual: "Hazelcast instance is not active!" not to contain: "is not active!" expected:<"[]is not active!"> but was:<"[Hazelcast instance ]is not active!"> at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:83) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:57) at com.hazelcast.mapstore.GenericMapStoreIntegrationTest.testInstanceShutdown(GenericMapStoreIntegrationTest.java:457) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:90) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) at java.lang.reflect.Method.invoke(Method.java:508) 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.InvokeMethod.evaluate(InvokeMethod.java:17) at com.hazelcast.test.FailOnTimeoutStatement$CallableStatement.call(FailOnTimeoutStatement.java:115) at com.hazelcast.test.FailOnTimeoutStatement$CallableStatement.call(FailOnTimeoutStatement.java:107) at java.util.concurrent.FutureTask.run(FutureTask.java:277) at java.lang.Thread.run(Thread.java:825) ```
Standard output: ``` Finished Running Test: testDynamicDataConnectionConfig in 0.259 seconds. Started Running Test: testInstanceShutdown 23:45:38,848 INFO |testInstanceShutdown| - [MetricsConfigHelper] testInstanceShutdown - [LOCAL] [dev] [5.3.2-SNAPSHOT] Overridden metrics configuration with system property 'hazelcast.metrics.collection.frequency'='1' -> 'MetricsConfig.collectionFrequencySeconds'='1' 23:45:38,850 INFO |testInstanceShutdown| - [logo] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-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 23:45:38,850 INFO |testInstanceShutdown| - [system] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Copyright (c) 2008-2023, Hazelcast, Inc. All Rights Reserved. 23:45:38,850 INFO |testInstanceShutdown| - [system] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Hazelcast Platform 5.3.2-SNAPSHOT (20230624 - f17d3e9) starting at [127.0.0.1]:5703 23:45:38,850 INFO |testInstanceShutdown| - [system] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Cluster name: dev 23:45:38,850 INFO |testInstanceShutdown| - [system] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Integrity Checker is disabled. Fail-fast on corrupted executables will not be performed. For more information, see the documentation for Integrity Checker. 23:45:38,850 INFO |testInstanceShutdown| - [system] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Jet is enabled 23:45:38,860 INFO |testInstanceShutdown| - [MetricsConfigHelper] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Collecting debug metrics and sending to diagnostics is enabled 23:45:38,861 INFO |testInstanceShutdown| - [TpcServerBootstrap] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] TPC: disabled 23:45:38,872 WARN |testInstanceShutdown| - [CPSubsystem] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-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. 23:45:38,884 INFO |testInstanceShutdown| - [JetServiceBackend] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Setting number of cooperative threads and default parallelism to 8 23:45:38,888 INFO |testInstanceShutdown| - [Diagnostics] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments. 23:45:38,888 INFO |testInstanceShutdown| - [LifecycleService] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] [127.0.0.1]:5703 is STARTING 23:45:38,889 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5701-bb77a892-738e-42a3-9fde-cf7fd8087244, connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], remoteEndpoint=[address=[127.0.0.1]:5701, uuid=bb77a892-738e-42a3-9fde-cf7fd8087244], alive=true} 23:45:38,890 INFO || - [MockServer] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.generic-operation.thread-2 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703-6523eb7e-eaaa-40a4-b567-e6201d0de21b, connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5701, uuid=bb77a892-738e-42a3-9fde-cf7fd8087244], remoteEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], alive=true} 23:45:38,892 INFO || - [ClusterService] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.generic-operation.thread-2 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Members {size:3, ver:3} [ Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 this Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b ] 23:45:38,899 INFO || - [MockServer] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.generic-operation.thread-1 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5703-6523eb7e-eaaa-40a4-b567-e6201d0de21b, connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5702, uuid=bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807], remoteEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], alive=true} 23:45:38,899 INFO || - [ClientClusterService] hz.client_1.event-14 - hz.client_1 [dev] [5.3.2-SNAPSHOT] Members [3] { Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b } 23:45:38,899 INFO || - [ClusterService] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.generic-operation.thread-1 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Members {size:3, ver:3} [ Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 this Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b ] 23:45:38,996 INFO |testInstanceShutdown| - [ClusterService] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Members {size:3, ver:3} [ Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b this ] 23:45:38,996 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Created connection to endpoint: [127.0.0.1]:5702-bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807, connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], remoteEndpoint=[address=[127.0.0.1]:5702, uuid=bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807], alive=true} 23:45:38,997 INFO |testInstanceShutdown| - [JobCoordinationService] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Jet started scanning for jobs 23:45:38,997 INFO |testInstanceShutdown| - [LifecycleService] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] [127.0.0.1]:5703 is STARTED 23:45:39,009 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-32 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testinstanceshutdown 23:45:39,053 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-47 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testinstanceshutdown 23:45:39,070 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-47 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for mapmysql_testinstanceshutdown 23:45:39,148 INFO || - [HazelcastClient] hz.client_1.internal-5 - Created connection to endpoint: [127.0.0.1]:5703, connection: MockedClientConnection{localAddress=[127.0.0.1]:40003, super=ClientConnection{alive=true, connectionId=3, channel=null, remoteAddress=null, lastReadTime=never, lastWriteTime=never, closedTime=never}} 23:45:39,149 INFO |testInstanceShutdown| - [AuthenticationMessageTask] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.priority-generic-operation.thread-0 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Received auth from MockedNodeConnection{ remoteAddress = [127.0.0.1]:40003, localAddress = [127.0.0.1]:5703, connectionId = 3}, successfully authenticated, clientUuid: 7a792a8b-b060-462d-8929-b57d30b0554d, client name: hz.client_1, client version: 5.3.2-SNAPSHOT 23:45:39,150 INFO || - [ClientConnectionManager] hz.client_1.internal-5 - hz.client_1 [dev] [5.3.2-SNAPSHOT] Authenticated with server [127.0.0.1]:5703:6523eb7e-eaaa-40a4-b567-e6201d0de21b, server version: 5.3.2-SNAPSHOT, local address: /127.0.0.1:40003 23:45:39,220 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.migration - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Repartitioning cluster data. Migration tasks count: 271 23:45:39,259 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-2 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testinstanceshutdown 23:45:39,268 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-3 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testputallwhenexists 23:45:39,270 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-1 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testput 23:45:39,382 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-2 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for mapmysql_testinstanceshutdown 23:45:39,742 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.migration - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] All migration tasks have been completed. (repartitionTime=Sat Jun 24 23:45:39 UTC 2023, plannedMigrations=271, completedMigrations=271, remainingMigrations=0, totalCompletedMigrations=271, elapsedMigrationOperationTime=4463ms, totalElapsedMigrationOperationTime=4463ms, elapsedDestinationCommitTime=149ms, totalElapsedDestinationCommitTime=149ms, elapsedMigrationTime=4685ms, totalElapsedMigrationTime=4685ms) 23:45:43,429 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-25 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testremovewhennotexists 23:45:43,489 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-13 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testremovewhennotexists 23:45:43,490 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-27 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Initializing for map mysql_testremovewhennotexists 23:45:43,506 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-13 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for mapmysql_testremovewhennotexists 23:45:43,513 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-27 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for mapmysql_testremovewhennotexists 23:45:43,675 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-6 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Initializing for map table_o_a4aa0bd5_b5ab_4cb9_96a7_5abf35328fd6 23:45:43,719 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-3 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Initializing for map table_o_a4aa0bd5_b5ab_4cb9_96a7_5abf35328fd6 23:45:43,722 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-28 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Initializing for map table_o_a4aa0bd5_b5ab_4cb9_96a7_5abf35328fd6 23:45:43,739 DEBUG || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-28 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for maptable_o_a4aa0bd5_b5ab_4cb9_96a7_5abf35328fd6 23:45:43,774 DEBUG |testInstanceShutdown| - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_wizardly_robinson.cached.thread-3 - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Reading existing mapping for maptable_o_a4aa0bd5_b5ab_4cb9_96a7_5abf35328fd6 23:45:49,717 INFO |testInstanceShutdown| - [LifecycleService] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] [127.0.0.1]:5703 is SHUTTING_DOWN 23:45:49,732 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.generic-operation.thread-2 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Shutdown request of Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b is handled 23:45:49,796 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.migration - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Repartitioning cluster data. Migration tasks count: 180 23:45:50,012 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.migration - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] All migration tasks have been completed. (repartitionTime=Sat Jun 24 23:45:49 UTC 2023, plannedMigrations=180, completedMigrations=180, remainingMigrations=0, totalCompletedMigrations=451, elapsedMigrationOperationTime=2711ms, totalElapsedMigrationOperationTime=7174ms, elapsedDestinationCommitTime=37ms, totalElapsedDestinationCommitTime=186ms, elapsedMigrationTime=2780ms, totalElapsedMigrationTime=7466ms) 23:45:50,014 INFO |testInstanceShutdown| - [Node] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Shutting down connection manager... 23:45:50,014 WARN || - [OutboundResponseHandler] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.generic-operation.thread-2 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Cannot send response: com.hazelcast.map.impl.query.QueryResult@95b2c9f4 to [127.0.0.1]:5703. com.hazelcast.map.impl.query.QueryOperation{serviceName='hz:impl:mapService', identityHash=711328467, partitionId=-1, replicaIndex=0, callId=9864, invocationTime=1687650350014 (2023-06-24 23:45:50.014), waitTimeout=-1, callTimeout=60000, tenantControl=com.hazelcast.spi.impl.tenantcontrol.NoopTenantControl@0, name=__sql.catalog} 23:45:50,015 WARN || - [OutboundResponseHandler] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.generic-operation.thread-0 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Cannot send response: com.hazelcast.map.impl.query.QueryResult@5f001a4c to [127.0.0.1]:5703. com.hazelcast.map.impl.query.QueryOperation{serviceName='hz:impl:mapService', identityHash=636490106, partitionId=-1, replicaIndex=0, callId=9863, invocationTime=1687650350014 (2023-06-24 23:45:50.014), waitTimeout=-1, callTimeout=60000, tenantControl=com.hazelcast.spi.impl.tenantcontrol.NoopTenantControl@0, name=__sql.catalog} 23:45:50,015 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5701, uuid=bb77a892-738e-42a3-9fde-cf7fd8087244], remoteEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], alive=false} 23:45:50,017 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [address=[127.0.0.1]:5701, uuid=bb77a892-738e-42a3-9fde-cf7fd8087244], connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], remoteEndpoint=[address=[127.0.0.1]:5701, uuid=bb77a892-738e-42a3-9fde-cf7fd8087244], alive=false} 23:45:50,017 WARN |testInstanceShutdown| - [TestClientRegistry$MockedServerConnection] testInstanceShutdown - Server connection closed: null 23:45:50,017 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [address=[127.0.0.1]:40003, uuid=7a792a8b-b060-462d-8929-b57d30b0554d], connection: MockedNodeConnection{ remoteAddress = [127.0.0.1]:40003, localAddress = [127.0.0.1]:5703, connectionId = 3} 23:45:50,018 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5702, uuid=bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807], remoteEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], alive=false} 23:45:50,018 INFO |testInstanceShutdown| - [MockServer] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [address=[127.0.0.1]:5702, uuid=bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807], connection: MockConnection{localEndpoint=[address=[127.0.0.1]:5703, uuid=6523eb7e-eaaa-40a4-b567-e6201d0de21b], remoteEndpoint=[address=[127.0.0.1]:5702, uuid=bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807], alive=false} 23:45:50,018 WARN |testInstanceShutdown| - [MembershipManager] testInstanceShutdown - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b is suspected to be dead for reason: Connection manager is stopped on Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b this 23:45:50,018 WARN |testInstanceShutdown| - [TcpClientConnection] pool-7-thread-1 - hz.client_1 [dev] [5.3.2-SNAPSHOT] MockedClientConnection{localAddress=[127.0.0.1]:40003, super=ClientConnection{alive=false, connectionId=3, channel=null, remoteAddress=[127.0.0.1]:5703, lastReadTime=2023-06-24 23:45:49.668, lastWriteTime=2023-06-24 23:45:49.627, closedTime=2023-06-24 23:45:50.018}} closed. Reason: com.hazelcast.spi.exception.TargetDisconnectedException[Mocked Remote socket closed] com.hazelcast.spi.exception.TargetDisconnectedException: Mocked Remote socket closed at com.hazelcast.client.test.TestClientRegistry$MockedTcpClientConnection$4.run(TestClientRegistry.java:332) [hazelcast-5.3.2-SNAPSHOT-tests.jar:5.3.2-SNAPSHOT] at com.hazelcast.client.test.TwoWayBlockableExecutor$BlockableRunnable.run(TwoWayBlockableExecutor.java:98) [hazelcast-5.3.2-SNAPSHOT-tests.jar:5.3.2-SNAPSHOT] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1160) [?:1.8.0] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:1.8.0] at java.lang.Thread.run(Thread.java:825) [?:2.9 (08-17-2022)] 23:45:50,019 INFO |testInstanceShutdown| - [MembershipManager] testInstanceShutdown - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Removing Member [127.0.0.1]:5703 - 6523eb7e-eaaa-40a4-b567-e6201d0de21b 23:45:50,020 WARN |testInstanceShutdown| - [TwoWayBlockableExecutor] pool-7-thread-1 - Dropping outgoing runnable since other end closed. Client Closed EOF. MockedClientConnection{localAddress=[127.0.0.1]:40003, super=ClientConnection{alive=false, connectionId=3, channel=null, remoteAddress=[127.0.0.1]:5703, lastReadTime=2023-06-24 23:45:49.668, lastWriteTime=2023-06-24 23:45:49.627, closedTime=2023-06-24 23:45:50.018}} 23:45:50,021 INFO |testInstanceShutdown| - [ClientConnectionManager] pool-7-thread-1 - hz.client_1 [dev] [5.3.2-SNAPSHOT] Removed connection to endpoint: [127.0.0.1]:5703:6523eb7e-eaaa-40a4-b567-e6201d0de21b, connection: MockedClientConnection{localAddress=[127.0.0.1]:40003, super=ClientConnection{alive=false, connectionId=3, channel=null, remoteAddress=[127.0.0.1]:5703, lastReadTime=2023-06-24 23:45:49.668, lastWriteTime=2023-06-24 23:45:49.627, closedTime=2023-06-24 23:45:50.018}} 23:45:50,023 INFO || - [ClusterService] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.generic-operation.thread-0 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Members {size:2, ver:4} [ Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 this ] 23:45:50,023 INFO || - [ClientClusterService] hz.client_1.event-11 - hz.client_1 [dev] [5.3.2-SNAPSHOT] Members [2] { Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 } 23:45:50,024 INFO |testInstanceShutdown| - [ClusterService] testInstanceShutdown - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Members {size:2, ver:4} [ Member [127.0.0.1]:5701 - bb77a892-738e-42a3-9fde-cf7fd8087244 this Member [127.0.0.1]:5702 - bf6b0f1c-c0b3-4dca-a8dd-40a62c6c8807 ] 23:45:50,025 INFO || - [TransactionManagerService] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-12 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5703, UUID: 6523eb7e-eaaa-40a4-b567-e6201d0de21b 23:45:50,025 INFO || - [TransactionManagerService] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-29 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Committing/rolling-back live transactions of [127.0.0.1]:5703, UUID: 6523eb7e-eaaa-40a4-b567-e6201d0de21b 23:45:50,030 INFO |testInstanceShutdown| - [Node] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Shutting down node engine... 23:45:50,033 WARN |testInstanceShutdown| - [QueryStateRegistryUpdater] MySQLGenericMapStoreIntegrationTest_wizardly_robinson-query-state-checker - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Unexpected error when invoking query state registry updater action com.hazelcast.core.HazelcastInstanceNotActiveException: Hazelcast instance is not active! at com.hazelcast.spi.impl.operationservice.impl.InvocationRegistry.register(InvocationRegistry.java:140) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.doInvoke(Invocation.java:587) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.invoke0(Invocation.java:558) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.invoke(Invocation.java:241) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.OperationServiceImpl.invokeOnPartition(OperationServiceImpl.java:341) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread(QueryEngineImpl.java:342) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.lambda$dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread$1(QueryEngineImpl.java:334) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.util.PrimitiveIterator$OfInt.forEachRemaining(PrimitiveIterator.java:126) ~[?:1.8.0] at com.hazelcast.map.impl.query.QueryEngineImpl.dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread(QueryEngineImpl.java:333) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.doRunOnPartitionThreads(QueryEngineImpl.java:199) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.runOnGivenPartitions(QueryEngineImpl.java:145) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.execute(QueryEngineImpl.java:109) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1441) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1405) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.executePredicate(MapProxyImpl.java:801) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:779) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:773) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionStorage.dataConnections(DataConnectionStorage.java:68) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.getAllDataConnectionEntries(DataConnectionResolver.java:158) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.lambda$getTables$1(DataConnectionResolver.java:136) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.util.Collections$SingletonList.forEach(Collections.java:4835) ~[?:1.8.0] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.getTables(DataConnectionResolver.java:135) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.schema.SqlCatalog.(SqlCatalog.java:42) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.plan.cache.PlanCacheChecker.check(PlanCacheChecker.java:56) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.checkPlans(QueryStateRegistryUpdater.java:165) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.executeInterruptibly(QueryStateRegistryUpdater.java:148) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.run(QueryStateRegistryUpdater.java:131) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.lang.Thread.run(Thread.java:825) ~[?:2.9 (08-17-2022)] at ------ submitted from ------.() ~[?:?] at com.hazelcast.internal.util.ExceptionUtil.cloneExceptionWithFixedAsyncStackTrace(ExceptionUtil.java:348) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.returnOrThrowWithGetConventions(InvocationFuture.java:112) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.resolveAndThrowIfException(InvocationFuture.java:100) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.AbstractInvocationFuture.get(AbstractInvocationFuture.java:610) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.addResultsOfPredicate(QueryEngineImpl.java:215) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.doRunOnPartitionThreads(QueryEngineImpl.java:201) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.runOnGivenPartitions(QueryEngineImpl.java:145) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.execute(QueryEngineImpl.java:109) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1441) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1405) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.executePredicate(MapProxyImpl.java:801) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:779) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:773) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionStorage.dataConnections(DataConnectionStorage.java:68) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.getAllDataConnectionEntries(DataConnectionResolver.java:158) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.lambda$getTables$1(DataConnectionResolver.java:136) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.util.Collections$SingletonList.forEach(Collections.java:4835) ~[?:1.8.0] at com.hazelcast.jet.sql.impl.schema.DataConnectionResolver.getTables(DataConnectionResolver.java:135) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.schema.SqlCatalog.(SqlCatalog.java:42) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.plan.cache.PlanCacheChecker.check(PlanCacheChecker.java:56) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.checkPlans(QueryStateRegistryUpdater.java:165) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.executeInterruptibly(QueryStateRegistryUpdater.java:148) [hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.run(QueryStateRegistryUpdater.java:131) [hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.lang.Thread.run(Thread.java:825) [?:2.9 (08-17-2022)] 23:45:50,037 WARN |testInstanceShutdown| - [QueryStateRegistryUpdater] MySQLGenericMapStoreIntegrationTest_wizardly_robinson-query-state-checker - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Hazelcast instance is not active! com.hazelcast.core.HazelcastInstanceNotActiveException: Hazelcast instance is not active! at com.hazelcast.spi.impl.operationservice.impl.InvocationRegistry.register(InvocationRegistry.java:140) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.doInvoke(Invocation.java:587) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.invoke0(Invocation.java:558) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.Invocation.invoke(Invocation.java:241) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.OperationServiceImpl.invokeOnPartition(OperationServiceImpl.java:341) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread(QueryEngineImpl.java:342) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.lambda$dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread$1(QueryEngineImpl.java:334) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.util.PrimitiveIterator$OfInt.forEachRemaining(PrimitiveIterator.java:126) ~[?:1.8.0] at com.hazelcast.map.impl.query.QueryEngineImpl.dispatchPartitionScanQueryOnOwnerMemberOnPartitionThread(QueryEngineImpl.java:333) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.doRunOnPartitionThreads(QueryEngineImpl.java:199) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.runOnGivenPartitions(QueryEngineImpl.java:145) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.execute(QueryEngineImpl.java:109) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1441) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1405) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.executePredicate(MapProxyImpl.java:801) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:779) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:773) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.DataConnectionConsistencyChecker.check(DataConnectionConsistencyChecker.java:66) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.checkDataConnectionsConsistency(QueryStateRegistryUpdater.java:172) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.executeInterruptibly(QueryStateRegistryUpdater.java:148) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.run(QueryStateRegistryUpdater.java:132) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.lang.Thread.run(Thread.java:825) ~[?:2.9 (08-17-2022)] at ------ submitted from ------.() ~[?:?] at com.hazelcast.internal.util.ExceptionUtil.cloneExceptionWithFixedAsyncStackTrace(ExceptionUtil.java:348) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.returnOrThrowWithGetConventions(InvocationFuture.java:112) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.operationservice.impl.InvocationFuture.resolveAndThrowIfException(InvocationFuture.java:100) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.spi.impl.AbstractInvocationFuture.get(AbstractInvocationFuture.java:610) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.addResultsOfPredicate(QueryEngineImpl.java:215) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.doRunOnPartitionThreads(QueryEngineImpl.java:201) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.runOnGivenPartitions(QueryEngineImpl.java:145) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.query.QueryEngineImpl.execute(QueryEngineImpl.java:109) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1441) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxySupport.executeQueryInternal(MapProxySupport.java:1405) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.executePredicate(MapProxyImpl.java:801) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:779) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.map.impl.proxy.MapProxyImpl.values(MapProxyImpl.java:773) ~[hazelcast-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.DataConnectionConsistencyChecker.check(DataConnectionConsistencyChecker.java:66) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.checkDataConnectionsConsistency(QueryStateRegistryUpdater.java:172) ~[hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.executeInterruptibly(QueryStateRegistryUpdater.java:148) [hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at com.hazelcast.sql.impl.state.QueryStateRegistryUpdater$Worker.run(QueryStateRegistryUpdater.java:132) [hazelcast-sql-5.3.2-SNAPSHOT.jar:5.3.2-SNAPSHOT] at java.lang.Thread.run(Thread.java:825) [?:2.9 (08-17-2022)] 23:45:50,049 INFO || - [MigrationManager] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.migration - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Partition balance is ok, no need to repartition. 23:45:50,129 INFO |testInstanceShutdown| - [NodeExtension] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Destroying node NodeExtension. 23:45:50,130 INFO |testInstanceShutdown| - [Node] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] Hazelcast Shutdown is completed in 406 ms. 23:45:50,130 INFO |testInstanceShutdown| - [LifecycleService] testInstanceShutdown - [127.0.0.1]:5703 [dev] [5.3.2-SNAPSHOT] [127.0.0.1]:5703 is SHUTDOWN 23:45:50,235 INFO |testInstanceShutdown| - [SqlTestSupport] Time-limited test - Removing 4 cached plans in SqlTestSupport.@After 23:45:50,236 INFO |testInstanceShutdown| - [SqlTestSupport] Time-limited test - Removing 5 cached plans in SqlTestSupport.@After 23:45:50,238 INFO |testInstanceShutdown| - [SimpleTestInClusterSupport] Time-limited test - Ditching 0 jobs in SimpleTestInClusterSupport.@After: [] 23:45:50,238 INFO |testInstanceShutdown| - [SimpleTestInClusterSupport] Time-limited test - Destroying 3 distributed objects in SimpleTestInClusterSupport.@After: [hz:impl:mapService/mysql_testinstanceshutdown, hz:impl:mapService/__sql.catalog, hz:impl:flakeIdGeneratorService/__jet.ids] 23:45:50,239 INFO || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_pensive_robinson.cached.thread-23 - [127.0.0.1]:5701 [dev] [5.3.2-SNAPSHOT] Dropping mapping __map-store.mysql_testinstanceshutdown 23:45:50,241 INFO || - [GenericMapLoader] hz.MySQLGenericMapStoreIntegrationTest_keen_robinson.cached.thread-29 - [127.0.0.1]:5702 [dev] [5.3.2-SNAPSHOT] Dropping mapping __map-store.mysql_testinstanceshutdown BuildInfo right after testInstanceShutdown(com.hazelcast.mapstore.mysql.MySQLGenericMapStoreIntegrationTest): BuildInfo{version='5.3.2-SNAPSHOT', build='20230624', buildNumber=20230624, revision=f17d3e9, enterprise=false, serializationVersion=1} Hiccups measured while running test 'testInstanceShutdown(com.hazelcast.mapstore.mysql.MySQLGenericMapStoreIntegrationTest):' 23:45:35, accumulated pauses: 53 ms, max pause: 4 ms, pauses over 1000 ms: 0 23:45:40, accumulated pauses: 45 ms, max pause: 6 ms, pauses over 1000 ms: 0 23:45:45, accumulated pauses: 154 ms, max pause: 112 ms, pauses over 1000 ms: 0 23:45:50, accumulated pauses: 5 ms, max pause: 3 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-5.maintenance-IbmJDK8-nightly/90/

sumnerib commented 1 year ago

Failed again on master with openjdk-sts (openjdk-20): https://jenkins.hazelcast.com/job/Hazelcast-master-OpenJDK-sts-nightly/39/testReport/junit/com.hazelcast.mapstore.mysql/MySQLGenericMapStoreIntegrationTest/testInstanceShutdown/

frant-hartm commented 11 months ago

Closed by #25569