You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Yury Gerzhedovich (Jira)" <ji...@apache.org> on 2022/08/12 07:34:00 UTC
[jira] [Updated] (IGNITE-17520) Flaky ItIndexSpoolTest test
[ https://issues.apache.org/jira/browse/IGNITE-17520?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Yury Gerzhedovich updated IGNITE-17520:
---------------------------------------
Labels: ignite-3 (was: )
> Flaky ItIndexSpoolTest test
> ---------------------------
>
> Key: IGNITE-17520
> URL: https://issues.apache.org/jira/browse/IGNITE-17520
> Project: Ignite
> Issue Type: Improvement
> Components: sql
> Reporter: Yury Gerzhedovich
> Priority: Major
> Labels: ignite-3
>
> The test is flaky with low rate - https://ci.ignite.apache.org/test/-3031105502308176978?currentProjectId=ignite3_Test_IntegrationTests&branch=
> {code:java}
> // java.lang.AssertionErrorjava.lang.AssertionError: Duplicated rows detected: [org.apache.ignite.internal.table.TableRow@dc32c19, org.apache.ignite.internal.table.TableRow@dc32c56, org.apache.ignite.internal.table.TableRow@dc32c58, org.apache.ignite.internal.table.TableRow@dc32c77, org.apache.ignite.internal.table.TableRow@dc3415a, org.apache.ignite.internal.table.TableRow@dc34175, org.apache.ignite.internal.table.TableRow@dc341a2, org.apache.ignite.internal.table.TableRow@dc341b7, org.apache.ignite.internal.table.TableRow@dc341dc, org.apache.ignite.internal.table.TableRow@dc34011, org.apache.ignite.internal.table.TableRow@dc32c1c, org.apache.ignite.internal.table.TableRow@dc32c1d, org.apache.ignite.internal.table.TableRow@dc32c35, org.apache.ignite.internal.table.TableRow@dc32c39, org.apache.ignite.internal.table.TableRow@dc32c5d, org.apache.ignite.internal.table.TableRow@dc32c5a, org.apache.ignite.internal.table.TableRow@dc32c5b, org.apache.ignite.internal.table.TableRow@dc3415f, org.apache.ignite.internal.table.TableRow@dc34178, org.apache.ignite.internal.table.TableRow@dc3417e, org.apache.ignite.internal.table.TableRow@dc341b9, org.apache.ignite.internal.table.TableRow@dc341d8, org.apache.ignite.internal.table.TableRow@dc34010, org.apache.ignite.internal.table.TableRow@dc32c38, org.apache.ignite.internal.table.TableRow@dc32c57, org.apache.ignite.internal.table.TableRow@dc32c91, org.apache.ignite.internal.table.TableRow@dc32c92, org.apache.ignite.internal.table.TableRow@dc32c8b, org.apache.ignite.internal.table.TableRow@dc32cb4, org.apache.ignite.internal.table.TableRow@dc3415d, org.apache.ignite.internal.table.TableRow@dc3417f, org.apache.ignite.internal.table.TableRow@dc34189, org.apache.ignite.internal.table.TableRow@dc341d3, org.apache.ignite.internal.table.TableRow@dc32c16, org.apache.ignite.internal.table.TableRow@dc32c76, org.apache.ignite.internal.table.TableRow@dc32c73, org.apache.ignite.internal.table.TableRow@dc32c95, org.apache.ignite.internal.table.TableRow@dc3417b, org.apache.ignite.internal.table.TableRow@dc34199, org.apache.ignite.internal.table.TableRow@dc341a8, org.apache.ignite.internal.table.TableRow@dc341be, org.apache.ignite.internal.table.TableRow@dc341e0, org.apache.ignite.internal.table.TableRow@dc341fa, org.apache.ignite.internal.table.TableRow@dc3401a, org.apache.ignite.internal.table.TableRow@dc32c0f, org.apache.ignite.internal.table.TableRow@dc32c51, org.apache.ignite.internal.table.TableRow@dc32c94, org.apache.ignite.internal.table.TableRow@dc32cb1, org.apache.ignite.internal.table.TableRow@dc3415c, org.apache.ignite.internal.table.TableRow@dc34126, org.apache.ignite.internal.table.TableRow@dc3415b, org.apache.ignite.internal.table.TableRow@dc34181, org.apache.ignite.internal.table.TableRow@dc3417c, org.apache.ignite.internal.table.TableRow@dc3419a, org.apache.ignite.internal.table.TableRow@dc3419b, org.apache.ignite.internal.table.TableRow@dc341ba, org.apache.ignite.internal.table.TableRow@dc34183, org.apache.ignite.internal.table.TableRow@dc341bd, org.apache.ignite.internal.table.TableRow@dc341f4, org.apache.ignite.internal.table.TableRow@dc34016, org.apache.ignite.internal.table.TableRow@dc32c9f, org.apache.ignite.internal.table.TableRow@dc32caa, org.apache.ignite.internal.table.TableRow@dc34159, org.apache.ignite.internal.table.TableRow@dc3417a, org.apache.ignite.internal.table.TableRow@dc341b6, org.apache.ignite.internal.table.TableRow@dc341d6, org.apache.ignite.internal.table.TableRow@dc341db, org.apache.ignite.internal.table.TableRow@dc341f7, org.apache.ignite.internal.table.TableRow@dc32c15, org.apache.ignite.internal.table.TableRow@dc32c1a, org.apache.ignite.internal.table.TableRow@dc32c42, org.apache.ignite.internal.table.TableRow@dc32c52, org.apache.ignite.internal.table.TableRow@dc32c53, org.apache.ignite.internal.table.TableRow@dc32c7a, org.apache.ignite.internal.table.TableRow@dc32c99, org.apache.ignite.internal.table.TableRow@dc32cbe, org.apache.ignite.internal.table.TableRow@dc32cb3, org.apache.ignite.internal.table.TableRow@dc34180, org.apache.ignite.internal.table.TableRow@dc34197, org.apache.ignite.internal.table.TableRow@dc3419e, org.apache.ignite.internal.table.TableRow@dc3419d, org.apache.ignite.internal.table.TableRow@dc341af, org.apache.ignite.internal.table.TableRow@dc341b8, org.apache.ignite.internal.table.TableRow@dc341d5, org.apache.ignite.internal.table.TableRow@dc341d7, org.apache.ignite.internal.table.TableRow@dc341d9, org.apache.ignite.internal.table.TableRow@dc341f8, org.apache.ignite.internal.table.TableRow@dc32c14, org.apache.ignite.internal.table.TableRow@dc32c18, org.apache.ignite.internal.table.TableRow@dc32c37, org.apache.ignite.internal.table.TableRow@dc32c54, org.apache.ignite.internal.table.TableRow@dc32c3c, org.apache.ignite.internal.table.TableRow@dc32c84, org.apache.ignite.internal.table.TableRow@dc32caf, org.apache.ignite.internal.table.TableRow@dc34160, org.apache.ignite.internal.table.TableRow@dc34164, org.apache.ignite.internal.table.TableRow@dc34195, org.apache.ignite.internal.table.TableRow@dc34194, org.apache.ignite.internal.table.TableRow@dc32c31, org.apache.ignite.internal.table.TableRow@dc32c33, org.apache.ignite.internal.table.TableRow@dc32c3c, org.apache.ignite.internal.table.TableRow@dc32c6f, org.apache.ignite.internal.table.TableRow@dc32c90, org.apache.ignite.internal.table.TableRow@dc32c96, org.apache.ignite.internal.table.TableRow@dc32cb5, org.apache.ignite.internal.table.TableRow@dc32cb2, org.apache.ignite.internal.table.TableRow@dc34157, org.apache.ignite.internal.table.TableRow@dc34015, org.apache.ignite.internal.table.TableRow@dc32c23, org.apache.ignite.internal.table.TableRow@dc32c2e, org.apache.ignite.internal.table.TableRow@dc32c36, org.apache.ignite.internal.table.TableRow@dc32c71, org.apache.ignite.internal.table.TableRow@dc32c70, org.apache.ignite.internal.table.TableRow@dc32c75, org.apache.ignite.internal.table.TableRow@dc32c98, org.apache.ignite.internal.table.TableRow@dc32cad, org.apache.ignite.internal.table.TableRow@dc32cb8, org.apache.ignite.internal.table.TableRow@dc341bc, org.apache.ignite.internal.table.TableRow@dc341e2, org.apache.ignite.internal.table.TableRow@dc341fd, org.apache.ignite.internal.table.TableRow@dc341f6, org.apache.ignite.internal.table.TableRow@dc341f1, org.apache.ignite.internal.table.TableRow@dc341fb, org.apache.ignite.internal.table.TableRow@dc341fc, org.apache.ignite.internal.table.TableRow@dc34205, org.apache.ignite.internal.table.TableRow@dc34013, org.apache.ignite.internal.table.TableRow@dc34017, org.apache.ignite.internal.table.TableRow@dc341e4] at org.apache.ignite.internal.sql.engine.ItIndexSpoolTest.prepareDataSet(ItIndexSpoolTest.java:108) at org.apache.ignite.internal.sql.engine.ItIndexSpoolTest.test(ItIndexSpoolTest.java:66)------- Stderr: -------2022-08-11 23:46:05:685 +0300 [INFO][main][ItIndexSpoolTest] >>> Starting test: ItIndexSpoolTest#test, displayName: tableSize=2000, workDir: /opt/buildagent/work/b8d4df1365f1f1e5/modules/runner/target/work/ItIndexSpoolTest/static_174069872876110832022-08-11 23:46:05:792 +0300 [INFO][tableManager-io24][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_0 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io6][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_1 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io8][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_4 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io15][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_6 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io2][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_9 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io19][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_8 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:792 +0300 [INFO][tableManager-io14][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_2 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io6][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io24][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io6][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io24][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io8][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io8][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:793 +0300 [INFO][tableManager-io15][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io19][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:793 +0300 [INFO][tableManager-io2][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io24][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io2][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io15][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io24][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344>2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io14][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io6][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344>2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io14][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:793 +0300 [INFO][tableManager-io19][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:793 +0300 [INFO][tableManager-io8][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:794 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:794 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io8][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344>2022-08-11 23:46:05:794 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io2][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:794 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:794 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:794 +0300 [INFO][tableManager-io15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io15][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344>2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io14][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344>2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io2][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344>2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io19][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:794 +0300 [INFO][tableManager-io19][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344>2022-08-11 23:46:05:805 +0300 [INFO][tableManager-io6][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_5 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3345, priority=-1]]2022-08-11 23:46:05:805 +0300 [INFO][tableManager-io1][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_3 with initial peers=[Peer [addr=172.17.0.7:3345, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:805 +0300 [INFO][tableManager-io7][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_7 with initial peers=[Peer [addr=172.17.0.7:3345, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io1][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io6][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io1][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864062.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io6][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864080.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io7][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io7][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864062.2022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3345.2022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640622022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640802022-08-11 23:46:05:806 +0300 [INFO][tableManager-io1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:806 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640622022-08-11 23:46:05:806 +0300 [INFO][tableManager-io1][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345>2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3345, oldConf=.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io7][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io6][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345>2022-08-11 23:46:05:806 +0300 [INFO][tableManager-io7][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345>2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io17][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_0 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io10][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_2 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io23][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_1 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io20][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_3 with initial peers=[Peer [addr=172.17.0.7:3345, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io3][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_5 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3345, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io8][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_9 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io18][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_7 with initial peers=[Peer [addr=172.17.0.7:3345, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io9][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_8 with initial peers=[Peer [addr=172.17.0.7:3344, priority=-1], Peer [addr=172.17.0.7:3346, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io22][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_6 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io21][Loza] Start new raft node for group=d980711d-9114-400c-8b6d-af3d15d528d9_part_4 with initial peers=[Peer [addr=172.17.0.7:3346, priority=-1], Peer [addr=172.17.0.7:3344, priority=-1]]2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io17][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io17][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io23][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io20][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io23][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io3][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io20][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864062.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io22][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io3][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864080.2022-08-11 23:46:05:807 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:807 +0300 [INFO][tableManager-io8][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io22][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:807 +0300 [INFO][tableManager-io9][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io10][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io8][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io21][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3345.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io17][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346>2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io18][FSMCallerImpl] Starts FSMCaller successfully.2022-08-11 23:46:05:807 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640802022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io18][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864062.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io21][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io9][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io10][SnapshotExecutorImpl] Loading snapshot, meta=org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec864043.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640622022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io22][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onConfigurationCommitted: 172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3345, oldConf=.2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640622022-08-11 23:46:05:808 +0300 [INFO][tableManager-io23][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io3][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346>2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:808 +0300 [INFO][tableManager-io22][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346>2022-08-11 23:46:05:808 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:809 +0300 [INFO][tableManager-io18][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io20][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:809 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io20][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io21][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:809 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec86407f2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io21][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io10][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io23][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][SnapshotExecutorImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> onSnapshotLoadDone, org.apache.ignite.raft.jraft.entity.SnapshotMetaImpl@ec8640432022-08-11 23:46:05:809 +0300 [INFO][tableManager-io8][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> init, term=0, lastLogId=LogId [index=0, term=0], conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io8][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346>2022-08-11 23:46:05:809 +0300 [INFO][tableManager-io9][RaftGroupService] Start the RaftGroupService successfully <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346>2022-08-11 23:46:07:086 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> term 0 start preVote.2022-08-11 23:46:07:087 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-168][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:087 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=0, granted=true.2022-08-11 23:46:07:088 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> start vote and grant vote self, term=0.2022-08-11 23:46:07:088 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-169][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=1, currTerm=0.2022-08-11 23:46:07:098 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-1][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3344, cost time=10 ms2022-08-11 23:46:07:098 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-169][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=1, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:07:107 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-169][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:07:108 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-2][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> become leader of group, term=1, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:108 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-2][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:07:108 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:116 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:129 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-2][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:130 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-138][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-7][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=0, granted=true.2022-08-11 23:46:07:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-7][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:131 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-135][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-7][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=10 ms2022-08-11 23:46:07:141 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-135][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3344/meta, term=1, votedFor=0.0.0.0:0, cost time=9 ms2022-08-11 23:46:07:150 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-135][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:150 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-8][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:150 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-8][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:07:151 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:158 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:277 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-0][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:278 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-150][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:278 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3345, term=0, granted=true.2022-08-11 23:46:07:278 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:279 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-151][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:285 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:286 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-149][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:286 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=0, granted=true.2022-08-11 23:46:07:286 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:286 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-151][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:288 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-10][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=10 ms2022-08-11 23:46:07:289 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-151][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3345/meta, term=1, votedFor=0.0.0.0:0, cost time=11 ms2022-08-11 23:46:07:290 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-151][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3344/meta, term=1, votedFor=0.0.0.0:0, cost time=3 ms2022-08-11 23:46:07:290 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-12][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=3 ms2022-08-11 23:46:07:292 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> term 0 start preVote.2022-08-11 23:46:07:293 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-189][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:294 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-4][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=0, granted=true.2022-08-11 23:46:07:294 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-4][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> start vote and grant vote self, term=0.2022-08-11 23:46:07:294 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-191][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=1, currTerm=0.2022-08-11 23:46:07:299 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-191][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=1, votedFor=0.0.0.0:0, cost time=4 ms2022-08-11 23:46:07:299 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-4][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3344, cost time=5 ms2022-08-11 23:46:07:299 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-151][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:299 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-151][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3345/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:299 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-13][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:07:300 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:300 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-13][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:07:300 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-14][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3345, type=Follower]@172.17.0.7:3345 is started2022-08-11 23:46:07:301 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:302 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:308 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:308 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-191][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:07:309 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-5][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> become leader of group, term=1, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:309 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-5][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:07:309 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:316 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:325 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:384 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:385 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-152][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:385 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-16][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=0, granted=true.2022-08-11 23:46:07:385 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-16][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:386 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-154][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:395 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-16][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:395 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-154][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3344/meta, term=1, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:07:404 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-154][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:405 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:07:405 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-18][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:07:406 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:413 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:421 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-11][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:421 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-163][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:422 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-19][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=0, granted=true.2022-08-11 23:46:07:422 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-19][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:422 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-166][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:431 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-19][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=10 ms2022-08-11 23:46:07:432 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-166][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3344/meta, term=1, votedFor=0.0.0.0:0, cost time=9 ms2022-08-11 23:46:07:441 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-166][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:441 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-20][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:07:441 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-20][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:07:442 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:450 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:471 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-ElectionTimer-0][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> term 0 start preVote.2022-08-11 23:46:07:472 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3345, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:472 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> received PreVoteResponse from 172.17.0.7:3346, term=0, granted=true.2022-08-11 23:46:07:472 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> start vote and grant vote self, term=0.2022-08-11 23:46:07:473 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3345, term=1, currTerm=0.2022-08-11 23:46:07:474 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-17][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=1, votedFor=0.0.0.0:0, cost time=1 ms2022-08-11 23:46:07:474 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-9][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3345/meta, term=1, votedFor=172.17.0.7:3345, cost time=2 ms2022-08-11 23:46:07:483 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-17][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3345, cost time=9 ms2022-08-11 23:46:07:484 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> become leader of group, term=1, conf=172.17.0.7:3346,172.17.0.7:3345, oldConf=.2022-08-11 23:46:07:484 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-10][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:07:484 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:493 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:665 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> term 0 start preVote.2022-08-11 23:46:07:666 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-172][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:666 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-22][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=0, granted=true.2022-08-11 23:46:07:667 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-22][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> start vote and grant vote self, term=0.2022-08-11 23:46:07:667 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-174][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=1, currTerm=0.2022-08-11 23:46:07:676 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-22][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3346, cost time=10 ms2022-08-11 23:46:07:677 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-174][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3344/meta, term=1, votedFor=0.0.0.0:0, cost time=9 ms2022-08-11 23:46:07:686 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-174][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3344/meta, term=1, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:07:687 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-23][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> become leader of group, term=1, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:687 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-23][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:07:688 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:695 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:745 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-ElectionTimer-3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> term 0 start preVote.2022-08-11 23:46:07:747 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-28][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3345, term=1, currTerm=0, granted=true, requestLastLogId=LogId [index=0, term=0], lastLogId=LogId [index=0, term=0].2022-08-11 23:46:07:747 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> received PreVoteResponse from 172.17.0.7:3346, term=0, granted=true.2022-08-11 23:46:07:747 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> start vote and grant vote self, term=0.2022-08-11 23:46:07:748 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-29][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3345, term=1, currTerm=0.2022-08-11 23:46:07:750 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-12][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3345/meta, term=1, votedFor=172.17.0.7:3345, cost time=1 ms2022-08-11 23:46:07:750 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-29][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3346/meta, term=1, votedFor=0.0.0.0:0, cost time=1 ms2022-08-11 23:46:07:758 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-29][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3346/meta, term=1, votedFor=172.17.0.7:3345, cost time=9 ms2022-08-11 23:46:07:759 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-13][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> become leader of group, term=1, conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:07:759 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-13][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:07:760 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:07:767 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onLeaderStart: term=1.2022-08-11 23:46:07:802 +0300 [INFO][Thread-150330][ConfigurationRegistry] Failed to notify configuration listenerjava.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)2022-08-11 23:46:07:802 +0300 [WARNING][Thread-150330][MetaStorageServiceImpl] Unexpected exceptionjava.util.concurrent.CompletionException: java.lang.AssertionError at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870) at java.base/java.util.concurrent.CompletableFuture.uniWhenCompleteStage(CompletableFuture.java:883) at java.base/java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:2251) at org.apache.ignite.internal.configuration.ConfigurationRegistry.lambda$notificator$3(ConfigurationRegistry.java:340) at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195) at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1655) at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484) at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474) at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:550) at java.base/java.util.stream.AbstractPipeline.evaluateToArrayNode(AbstractPipeline.java:260) at java.base/java.util.stream.ReferencePipeline.toArray(ReferencePipeline.java:517) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:346) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)Caused by: java.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) ... 5 more2022-08-11 23:46:07:812 +0300 [INFO][Thread-150339][ConfigurationRegistry] Failed to notify configuration listenerjava.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)2022-08-11 23:46:07:812 +0300 [WARNING][Thread-150339][MetaStorageServiceImpl] Unexpected exceptionjava.util.concurrent.CompletionException: java.lang.AssertionError at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870) at java.base/java.util.concurrent.CompletableFuture.uniWhenCompleteStage(CompletableFuture.java:883) at java.base/java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:2251) at org.apache.ignite.internal.configuration.ConfigurationRegistry.lambda$notificator$3(ConfigurationRegistry.java:340) at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195) at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1655) at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484) at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474) at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:550) at java.base/java.util.stream.AbstractPipeline.evaluateToArrayNode(AbstractPipeline.java:260) at java.base/java.util.stream.ReferencePipeline.toArray(ReferencePipeline.java:517) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:346) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)Caused by: java.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) ... 5 more2022-08-11 23:46:07:813 +0300 [INFO][Thread-150332][ConfigurationRegistry] Failed to notify configuration listenerjava.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)2022-08-11 23:46:07:814 +0300 [WARNING][Thread-150332][MetaStorageServiceImpl] Unexpected exceptionjava.util.concurrent.CompletionException: java.lang.AssertionError at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:870) at java.base/java.util.concurrent.CompletableFuture.uniWhenCompleteStage(CompletableFuture.java:883) at java.base/java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:2251) at org.apache.ignite.internal.configuration.ConfigurationRegistry.lambda$notificator$3(ConfigurationRegistry.java:340) at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195) at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1655) at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484) at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474) at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:550) at java.base/java.util.stream.AbstractPipeline.evaluateToArrayNode(AbstractPipeline.java:260) at java.base/java.util.stream.ReferencePipeline.toArray(ReferencePipeline.java:517) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:346) at org.apache.ignite.internal.configuration.ConfigurationChanger.updateFromListener(ConfigurationChanger.java:580) at org.apache.ignite.internal.configuration.storage.DistributedConfigurationStorage$1.onUpdate(DistributedConfigurationStorage.java:314) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.processWatchEvents(WatchAggregator.java:201) at org.apache.ignite.internal.metastorage.watch.WatchAggregator$1.onUpdate(WatchAggregator.java:174) at org.apache.ignite.internal.metastorage.client.MetaStorageServiceImpl$WatchProcessor$Watcher.run(MetaStorageServiceImpl.java:561)Caused by: java.lang.AssertionError at org.apache.ignite.internal.index.IndexManager.onIndexCreate(IndexManager.java:356) at org.apache.ignite.internal.index.IndexManager$ConfigurationListener.onCreate(IndexManager.java:409) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyPublicListeners(ConfigurationNotifier.java:487) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:436) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$2.visitNamedListNode(ConfigurationNotifier.java:370) at org.apache.ignite.configuration.schemas.table.TableNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:370) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:224) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier$1.visitNamedListNode(ConfigurationNotifier.java:128) at org.apache.ignite.configuration.schemas.table.TablesNode.traverseChildren(Unknown Source) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:128) at org.apache.ignite.internal.configuration.notifications.ConfigurationNotifier.notifyListeners(ConfigurationNotifier.java:90) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:327) at org.apache.ignite.internal.configuration.ConfigurationRegistry$2.visitInnerNode(ConfigurationRegistry.java:309) at org.apache.ignite.internal.configuration.SuperRoot.traverseChildren(SuperRoot.java:103) at org.apache.ignite.internal.configuration.ConfigurationRegistry.notificator(ConfigurationRegistry.java:309) ... 5 more2022-08-11 23:46:09:089 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-StepDownTimer-3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3346, conf=172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-7][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> term 1 start preVote.2022-08-11 23:46:09:089 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ERAFTTIMEDOUT<10001>: Lost connection from leader 172.17.0.7:3346.]].2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-StepDownTimer-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3346, conf=172.17.0.7:3346,172.17.0.7:3345.2022-08-11 23:46:09:089 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ERAFTTIMEDOUT<10001>: Lost connection from leader 172.17.0.7:3346.]].2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-4][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3344, conf=172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-5][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3344, conf=172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3344, conf=172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:09:089 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-StepDownTimer-19][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3346, conf=172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-11][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3344, conf=172.17.0.7:3346,172.17.0.7:3344.2022-08-11 23:46:09:089 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> term 1 start preVote.2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3345, conf=172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_1%jvm-pause-detector-worker-][LongJvmPauseDetector] Possible too long JVM pause [duration=1094ms]2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-0][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3344, conf=172.17.0.7:3344,172.17.0.7:3346.2022-08-11 23:46:09:094 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-11][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-StepDownTimer-9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> steps down when alive nodes don't satisfy quorum, term=1, deadNodes=172.17.0.7:3346, conf=172.17.0.7:3345,172.17.0.7:3346.2022-08-11 23:46:09:094 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-1][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3345, type=Follower] is going to quit2022-08-11 23:46:09:098 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-ElectionTimer-6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> term 1 start preVote.2022-08-11 23:46:09:098 +0300 [WARNING][%ItIndexSpoolTest_null_0%jvm-pause-detector-worker-][LongJvmPauseDetector] Possible too long JVM pause [duration=1094ms]2022-08-11 23:46:09:098 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:090 +0300 [WARNING][%ItIndexSpoolTest_null_2%jvm-pause-detector-worker-][LongJvmPauseDetector] Possible too long JVM pause [duration=1094ms]2022-08-11 23:46:09:090 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:101 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:101 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-StepDownTimer-9][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:09:114 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-1][Replicator] Replicator Replicator [state=Probe, statInfo=<running=BLOCKING, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:09:118 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-4][Replicator] Replicator Replicator [state=Probe, statInfo=<running=BLOCKING, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:09:114 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-StepDownTimer-18][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:09:114 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-5][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:09:114 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-18][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:09:113 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> term 1 start preVote.2022-08-11 23:46:09:109 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:113 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onLeaderStop: status=Status[ERAFTTIMEDOUT<10001>: Majority of the group dies: 1/2].2022-08-11 23:46:09:118 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-StepDownTimer-19][Replicator] Replicator Replicator [state=Probe, statInfo=<running=BLOCKING, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:09:117 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-StepDownTimer-0][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=12, lastLogIncluded=0, lastLogIndex=12, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-99][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3345, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-183][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> received PreVoteRequest from 172.17.0.7:3346, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-101][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> start vote and grant vote self, term=1.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-103][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-25][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3345, term=1, granted=true.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-25][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> start vote and grant vote self, term=1.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-8][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-8][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> start vote and grant vote self, term=1.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> start vote and grant vote self, term=1.2022-08-11 23:46:09:127 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-184][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> received RequestVoteRequest from 172.17.0.7:3346, term=2, currTerm=1.2022-08-11 23:46:09:128 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-100][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3345, term=2, currTerm=1.2022-08-11 23:46:09:128 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-105][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=2, currTerm=1.2022-08-11 23:46:09:128 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-112][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=2, currTerm=1.2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-105][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-10][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-25][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-15][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3345, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-8][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-112][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-184][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3345/meta, term=2, votedFor=0.0.0.0:0, cost time=3 ms2022-08-11 23:46:09:131 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-100][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=3 ms2022-08-11 23:46:09:132 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[ERAFTTIMEDOUT<10001>: Lost connection from leader 172.17.0.7:3345.]].2022-08-11 23:46:09:132 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ERAFTTIMEDOUT<10001>: Lost connection from leader 172.17.0.7:3346.]].2022-08-11 23:46:09:132 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:132 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-105][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-184][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-112][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-100][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3345, cost time=9 ms2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-0][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> become leader of group, term=2, conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-16][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> become leader of group, term=2, conf=172.17.0.7:3345,172.17.0.7:3346, oldConf=.2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> become leader of group, term=2, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-11][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> become leader of group, term=2, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-0][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3345, type=Follower]@172.17.0.7:3345 is started2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-16][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-9][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:09:140 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-11][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:09:141 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:141 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:141 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:141 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:160 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:09:160 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:09:160 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:09:160 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:09:241 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-0][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> term 1 start preVote.2022-08-11 23:46:09:241 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[ERAFTTIMEDOUT<10001>: Lost connection from leader 172.17.0.7:3346.]].2022-08-11 23:46:09:243 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-109][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:09:243 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-13][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:09:243 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-13][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> start vote and grant vote self, term=1.2022-08-11 23:46:09:244 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-113][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=2, currTerm=1.2022-08-11 23:46:09:253 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-13][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=10 ms2022-08-11 23:46:09:254 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-113][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=11 ms2022-08-11 23:46:09:263 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-113][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3344, cost time=8 ms2022-08-11 23:46:09:263 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> become leader of group, term=2, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:09:263 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-14][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:09:264 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:09:272 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:10:391 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-4][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> term 1 start preVote.2022-08-11 23:46:10:392 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-128][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:10:392 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:10:392 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> start vote and grant vote self, term=1.2022-08-11 23:46:10:393 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-126][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=2, currTerm=1.2022-08-11 23:46:10:393 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=1, status=Status[EHIGHERTERMRESPONSE<10008>: Raft node receives higher term RequestVoteRequest.]].2022-08-11 23:46:10:394 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-15][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=2 ms2022-08-11 23:46:10:395 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-126][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=2 ms2022-08-11 23:46:10:403 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-126][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:10:404 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-16][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> become leader of group, term=2, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:10:404 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-16][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:10:404 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:10:413 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:10:519 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-ElectionTimer-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> term 1 start preVote.2022-08-11 23:46:10:520 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-127][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3344, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:10:520 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:10:520 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> start vote and grant vote self, term=1.2022-08-11 23:46:10:521 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-124][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3344, term=2, currTerm=1.2022-08-11 23:46:10:521 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=1, status=Status[EHIGHERTERMRESPONSE<10008>: Raft node receives higher term RequestVoteRequest.]].2022-08-11 23:46:10:530 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-17][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=10 ms2022-08-11 23:46:10:531 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-124][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:10:540 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-124][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3344, cost time=8 ms2022-08-11 23:46:10:540 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> become leader of group, term=2, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:10:540 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-18][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:10:541 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:10:549 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:10:680 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-ElectionTimer-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> term 1 start preVote.2022-08-11 23:46:10:681 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-131][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> received PreVoteRequest from 172.17.0.7:3345, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:10:681 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> received PreVoteResponse from 172.17.0.7:3346, term=1, granted=true.2022-08-11 23:46:10:681 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-17][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> start vote and grant vote self, term=1.2022-08-11 23:46:10:682 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-135][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> received RequestVoteRequest from 172.17.0.7:3345, term=2, currTerm=1.2022-08-11 23:46:10:683 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=1, status=Status[EHIGHERTERMRESPONSE<10008>: Raft node receives higher term RequestVoteRequest.]].2022-08-11 23:46:10:692 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-17][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3345, cost time=11 ms2022-08-11 23:46:10:693 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-135][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=2, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:10:702 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-135][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3345, cost time=9 ms2022-08-11 23:46:10:702 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-18][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> become leader of group, term=2, conf=172.17.0.7:3346,172.17.0.7:3345, oldConf=.2022-08-11 23:46:10:702 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-18][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower]@172.17.0.7:3346 is started2022-08-11 23:46:10:703 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:10:712 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:10:792 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> term 1 start preVote.2022-08-11 23:46:10:792 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-178][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:10:793 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=1, granted=true.2022-08-11 23:46:10:793 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-1][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> start vote and grant vote self, term=1.2022-08-11 23:46:10:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-183][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=2, currTerm=1.2022-08-11 23:46:10:793 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[EHIGHERTERMRESPONSE<10008>: Raft node receives higher term RequestVoteRequest.]].2022-08-11 23:46:10:803 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-1][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=10 ms2022-08-11 23:46:10:803 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-183][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3344/meta, term=2, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:10:812 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-183][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:10:813 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-2][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> become leader of group, term=2, conf=172.17.0.7:3346,172.17.0.7:3344, oldConf=.2022-08-11 23:46:10:813 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-2][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:10:814 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:10:822 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:11:287 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-ElectionTimer-14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> term 1 start preVote.2022-08-11 23:46:11:287 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-133][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> received PreVoteRequest from 172.17.0.7:3346, term=2, currTerm=1, granted=true, requestLastLogId=LogId [index=12, term=1], lastLogId=LogId [index=12, term=1].2022-08-11 23:46:11:288 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> received PreVoteResponse from 172.17.0.7:3344, term=1, granted=true.2022-08-11 23:46:11:288 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> start vote and grant vote self, term=1.2022-08-11 23:46:11:288 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-132][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> received RequestVoteRequest from 172.17.0.7:3346, term=2, currTerm=1.2022-08-11 23:46:11:289 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=1, status=Status[EHIGHERTERMRESPONSE<10008>: Raft node receives higher term RequestVoteRequest.]].2022-08-11 23:46:11:298 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-3][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:299 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-132][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3344/meta, term=2, votedFor=0.0.0.0:0, cost time=10 ms2022-08-11 23:46:11:308 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-132][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:308 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-4][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> become leader of group, term=2, conf=172.17.0.7:3344,172.17.0.7:3346, oldConf=.2022-08-11 23:46:11:308 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-4][Replicator] Replicator=Replicator [state=null, statInfo=<running=null, firstLogIndex=0, lastLogIncluded=0, lastLogIndex=0, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower]@172.17.0.7:3344 is started2022-08-11 23:46:11:308 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onStartFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].2022-08-11 23:46:11:317 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onLeaderStart: term=2.2022-08-11 23:46:11:598 +0300 [INFO][main][AbstractBasicIntegrationTest] Start cleanUp()2022-08-11 23:46:11:636 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_02022-08-11 23:46:11:636 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:637 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:637 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-16][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:637 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-142][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3344, term=2 and starts voting.2022-08-11 23:46:11:637 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-142][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:637 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_7-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:637 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-19][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:642 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=5 ms2022-08-11 23:46:11:642 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-142][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=4 ms2022-08-11 23:46:11:642 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:642 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-69][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> is not in active state, currTerm=2.2022-08-11 23:46:11:642 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:642 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_12022-08-11 23:46:11:642 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:642 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:642 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-29][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:643 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> RequestVote to 172.17.0.7:3344 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3344> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:644 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-136][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3344, term=2 and starts voting.2022-08-11 23:46:11:644 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-136][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:644 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-20][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:644 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_8-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:645 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_02022-08-11 23:46:11:645 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_0/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:651 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=8 ms2022-08-11 23:46:11:651 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_0_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=6 ms2022-08-11 23:46:11:651 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:651 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:651 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-66][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> is not in active state, currTerm=2.2022-08-11 23:46:11:651 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:651 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:651 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-136][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:651 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_22022-08-11 23:46:11:651 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:651 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_12022-08-11 23:46:11:651 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:651 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-7][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3346> RequestVote to 172.17.0.7:3344 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_1/172.17.0.7:3344> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:651 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:652 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-26][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:652 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-143][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3344, term=2 and starts voting.2022-08-11 23:46:11:652 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-143][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:652 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_9-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:652 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-21][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:652 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_02022-08-11 23:46:11:653 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_12022-08-11 23:46:11:653 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_22022-08-11 23:46:11:653 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_32022-08-11 23:46:11:653 +0300 [INFO][Thread-150332][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3345> shutdown, currTerm=2 state=STATE_FOLLOWER.2022-08-11 23:46:11:653 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ESHUTDOWN<1007>: Raft node is going to quit.]].2022-08-11 23:46:11:660 +0300 [INFO][Thread-150332][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:660 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_1_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:660 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-143][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:660 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=9 ms2022-08-11 23:46:11:661 +0300 [INFO][Thread-150332][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:661 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:661 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:661 +0300 [INFO][Thread-150332][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_42022-08-11 23:46:11:661 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-67][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> is not in active state, currTerm=2.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_52022-08-11 23:46:11:661 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_22022-08-11 23:46:11:661 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150332][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:661 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_32022-08-11 23:46:11:661 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_42022-08-11 23:46:11:661 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:661 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:661 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:661 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-9][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3346> RequestVote to 172.17.0.7:3344 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_2/172.17.0.7:3344> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:661 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-1][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:661 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-Common-Executor-7][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-145][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3345, term=2 and starts voting.2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-147][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3344, term=2 and starts voting.2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-145][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-147][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-19][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_11-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-22][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:662 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_12-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:670 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=8 ms2022-08-11 23:46:11:670 +0300 [INFO][Thread-150332][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3345, cost time=8 ms2022-08-11 23:46:11:670 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_2_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:670 +0300 [INFO][Thread-150332][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:670 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:670 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-145][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:670 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:670 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-147][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:670 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-70][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> is not in active state, currTerm=2.2022-08-11 23:46:11:670 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-3][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> is not in active state, currTerm=2.2022-08-11 23:46:11:670 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:670 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:670 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_32022-08-11 23:46:11:670 +0300 [INFO][Thread-150332][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:670 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_52022-08-11 23:46:11:670 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_3/172.17.0.7:3346> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:671 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_62022-08-11 23:46:11:671 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_62022-08-11 23:46:11:671 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_72022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_10-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:671 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:671 +0300 [INFO][Thread-150332][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:671 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-12][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> RequestVote to 172.17.0.7:3345 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3345> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:671 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-10][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> RequestVote to 172.17.0.7:3344 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3344> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:671 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Common-Executor-9][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3345, type=Follower] stops sending entries.2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:671 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Common-Executor-7][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:671 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-Common-Executor-4][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] stops sending entries.2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-149][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3344, term=2 and starts voting.2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-148][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> received TimeoutNowRequest from 172.17.0.7:3345, term=2 and starts voting.2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-13][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3345, type=Follower] is going to quit2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-149][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_1%JRaft-Response-Processor-20][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-Response-Processor-23][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3346, type=Follower] is going to quit2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_13-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3344, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:671 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-148][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> start vote and grant vote self, term=2.2022-08-11 23:46:11:672 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_14-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3345, term=2, status=Status[ERAFTTIMEDOUT<10001>: A follower's leader_id is reset to NULL as it begins to request_vote.]].2022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3344, cost time=8 ms2022-08-11 23:46:11:679 +0300 [INFO][Thread-150332][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_1/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3345/meta, term=2, votedFor=172.17.0.7:3345, cost time=8 ms2022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:679 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-149][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=7 ms2022-08-11 23:46:11:679 +0300 [INFO][Thread-150332][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:679 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_3_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:679 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Request-Processor-148][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=7 ms2022-08-11 23:46:11:679 +0300 [WARNING][%ItIndexSpoolTest_null_0%JRaft-Request-Processor-71][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> is not in active state, currTerm=2.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:679 +0300 [WARNING][%ItIndexSpoolTest_null_1%JRaft-Request-Processor-6][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> is not in active state, currTerm=2.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_72022-08-11 23:46:11:679 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_82022-08-11 23:46:11:679 +0300 [INFO][Thread-150332][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_42022-08-11 23:46:11:679 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_FOLLOWER.2022-08-11 23:46:11:679 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_82022-08-11 23:46:11:679 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_4/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:679 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ESHUTDOWN<1007>: Raft node is going to quit.]].2022-08-11 23:46:11:679 +0300 [INFO][Thread-150332][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_92022-08-11 23:46:11:679 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-14][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> RequestVote to 172.17.0.7:3344 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3344> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:679 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-15][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> RequestVote to 172.17.0.7:3345 error: Status[EINVAL<1015>: Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3345> is not in active state, state STATE_SHUTTING.].2022-08-11 23:46:11:691 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_4_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=11 ms2022-08-11 23:46:11:691 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3346, cost time=11 ms2022-08-11 23:46:11:691 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:691 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:691 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:691 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:691 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_52022-08-11 23:46:11:691 +0300 [INFO][Thread-150330][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_92022-08-11 23:46:11:691 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_5/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:691 +0300 [INFO][Thread-150330][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3344> shutdown, currTerm=2 state=STATE_FOLLOWER.2022-08-11 23:46:11:692 +0300 [INFO][%ItIndexSpoolTest_null_0%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onStopFollowing: LeaderChangeContext [leaderId=172.17.0.7:3346, term=2, status=Status[ESHUTDOWN<1007>: Raft node is going to quit.]].2022-08-11 23:46:11:700 +0300 [INFO][Thread-150330][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_0/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3344/meta, term=2, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:700 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_5_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=8 ms2022-08-11 23:46:11:700 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:700 +0300 [INFO][Thread-150330][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:700 +0300 [INFO][Thread-150330][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:700 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:700 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_62022-08-11 23:46:11:700 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_6/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:712 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_6_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=12 ms2022-08-11 23:46:11:712 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:712 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:712 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_72022-08-11 23:46:11:712 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_7/172.17.0.7:3346> shutdown, currTerm=3 state=STATE_CANDIDATE.2022-08-11 23:46:11:721 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_7_172.17.0.7_3346/meta, term=3, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:721 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:721 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:721 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_82022-08-11 23:46:11:721 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_8/172.17.0.7:3346> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:721 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_15-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:721 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Common-Executor-22][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] stops sending entries.2022-08-11 23:46:11:722 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-16][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:11:730 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_8_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:730 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:731 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:731 +0300 [INFO][Thread-150339][Loza] Stop raft group=d980711d-9114-400c-8b6d-af3d15d528d9_part_92022-08-11 23:46:11:731 +0300 [INFO][Thread-150339][NodeImpl] Node <d980711d-9114-400c-8b6d-af3d15d528d9_part_9/172.17.0.7:3346> shutdown, currTerm=2 state=STATE_LEADER.2022-08-11 23:46:11:731 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-FSMCaller-Disruptor-_stripe_16-0][StateMachineAdapter] onLeaderStop: status=Status[ESHUTDOWN<1007>: Raft node is going to quit.].2022-08-11 23:46:11:731 +0300 [WARNING][%ItIndexSpoolTest_null_2%JRaft-Common-Executor-7][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] stops sending entries.2022-08-11 23:46:11:732 +0300 [INFO][%ItIndexSpoolTest_null_2%JRaft-Response-Processor-18][Replicator] Replicator Replicator [state=Replicate, statInfo=<running=IDLE, firstLogIndex=15, lastLogIncluded=0, lastLogIndex=15, lastTermIncluded=0>, peerId=172.17.0.7:3344, type=Follower] is going to quit2022-08-11 23:46:11:739 +0300 [INFO][Thread-150339][LocalRaftMetaStorage] Save raft meta, path=target/work/ItIndexSpoolTest/static_17406987287611083/ItIndexSpoolTest_null_2/d980711d-9114-400c-8b6d-af3d15d528d9_part_9_172.17.0.7_3346/meta, term=2, votedFor=172.17.0.7:3346, cost time=9 ms2022-08-11 23:46:11:739 +0300 [INFO][Thread-150339][FSMCallerImpl] Shutting down FSMCaller...2022-08-11 23:46:11:739 +0300 [INFO][Thread-150339][RaftGroupService] Stop the RaftGroupService successfully.2022-08-11 23:46:11:743 +0300 [INFO][main][AbstractBasicIntegrationTest] End cleanUp()2022-08-11 23:46:11:743 +0300 [INFO][main][ItIndexSpoolTest] >>> Stopping test: ItIndexSpoolTest#test, displayName: tableSize=2000, cost: 6059ms. {code}
--
This message was sent by Atlassian Jira
(v8.20.10#820010)