[
https://issues.apache.org/jira/browse/IOTDB-4484?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17608022#comment-17608022
]
Chao Wang commented on IOTDB-4484:
----------------------------------
2022-09-21 22:16:10,770 | INFO | [192.168.0.124_22252-server-thread1] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:11,774 | INFO | [192.168.0.124_22252-server-thread2] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:12,777 | INFO | [192.168.0.124_22252-server-thread1] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:13,781 | INFO | [192.168.0.124_22252-server-thread2] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:14,784 | INFO | [192.168.0.124_22252-server-thread1] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:15,787 | INFO | [192.168.0.124_22252-server-thread2] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:16,791 | INFO | [192.168.0.124_22252-server-thread1] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:17,799 | INFO | [192.168.0.124_22252-server-thread2] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:18,802 | INFO | [192.168.0.124_22252-server-thread1] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
2022-09-21 22:16:19,806 | INFO | [192.168.0.124_22252-server-thread2] |
Reading snapshot log file
/srv/BigData/data1/iotdb/iotdbserver/consensus/data_region/47474747-4747-4747-4747-000100000003/sm/1_400000/snapshot.log
| org.apache.iotdb.db.engine.snapshot.SnapshotLoader (SnapshotLoader.java:93)
> ratis election All the time.
> ----------------------------
>
> Key: IOTDB-4484
> URL: https://issues.apache.org/jira/browse/IOTDB-4484
> Project: Apache IoTDB
> Issue Type: Bug
> Components: mpp-cluster
> Affects Versions: 0.14.0-preview2
> Reporter: Chao Wang
> Assignee: Song Ziyang
> Priority: Major
>
> 2022-09-21 11:46:32,151 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C-LeaderElection17574 PRE_VOTE round 0:
> submit vote requests at term 4 for 45:
> peers:[8.5.226.7_22252|rpc:8.5.226.7:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER,
>
> 8.5.226.6_22252|rpc:8.5.226.6:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER,
>
> 8.5.155.13_22252|rpc:8.5.155.13:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER]|listeners:[],
> old=null | org.apache.ratis.server.impl.LeaderElection
> (LeaderElection.java:307)
> 2022-09-21 11:46:32,151 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> raft.server.rpc.first-election.timeout.min = 2s (fallback to
> raft.server.rpc.timeout.min) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> raft.server.rpc.first-election.timeout.max = 4s (fallback to
> raft.server.rpc.timeout.max) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C-LeaderElection17574 got exception when
> requesting votes: java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C-LeaderElection17574 got exception when
> requesting votes: java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C-LeaderElection17574: PRE_VOTE REJECTED
> received 0 response(s) and 2 exception(s): |
> org.apache.ratis.server.impl.LeaderElection (LeaderElection.java:89)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] | Exception 0:
> java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] | Exception 1:
> java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C-LeaderElection17574 PRE_VOTE round 0:
> result REJECTED | org.apache.ratis.server.impl.LeaderElection
> (LeaderElection.java:309)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] |
> 8.5.226.6_22252@group-00010000026C: changes role from CANDIDATE to FOLLOWER
> at term 4 for REJECTED | org.apache.ratis.server.RaftServer$Division
> (RaftServerImpl.java:317)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] | 8.5.226.6_22252:
> shutdown 8.5.226.6_22252@group-00010000026C-LeaderElection17574 |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:130)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-LeaderElection17574] | 8.5.226.6_22252:
> start 8.5.226.6_22252@group-00010000026C-FollowerState |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:139)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-FollowerState] |
> raft.server.rpc.first-election.timeout.min = 2s (fallback to
> raft.server.rpc.timeout.min) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,152 | INFO |
> [8.5.226.6_22252@group-00010000026C-FollowerState] |
> raft.server.rpc.first-election.timeout.max = 4s (fallback to
> raft.server.rpc.timeout.max) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] |
> 8.5.226.6_22252@group-000100000272-FollowerState: change to CANDIDATE,
> lastRpcElapsedTime:3985436262ns, electionTimeout:3985ms |
> org.apache.ratis.server.impl.FollowerState (FollowerState.java:143)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] | 8.5.226.6_22252:
> shutdown 8.5.226.6_22252@group-000100000272-FollowerState |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:110)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] |
> 8.5.226.6_22252@group-000100000272: changes role from FOLLOWER to CANDIDATE
> at term 9 for changeToCandidate | org.apache.ratis.server.RaftServer$Division
> (RaftServerImpl.java:317)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] |
> raft.server.leaderelection.pre-vote = true (default) |
> org.apache.ratis.server.RaftServerConfigKeys (ConfUtils.java:46)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] | 8.5.226.6_22252: start
> 8.5.226.6_22252@group-000100000272-LeaderElection17575 |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:139)
> 2022-09-21 11:46:32,212 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272-LeaderElection17575 PRE_VOTE round 0:
> submit vote requests at term 9 for 45:
> peers:[8.5.226.7_22252|rpc:8.5.226.7:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER,
>
> 8.5.226.6_22252|rpc:8.5.226.6:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER,
>
> 8.5.155.13_22252|rpc:8.5.155.13:22252|admin:|client:|dataStream:|priority:0|startupRole:FOLLOWER]|listeners:[],
> old=null | org.apache.ratis.server.impl.LeaderElection
> (LeaderElection.java:307)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> raft.server.rpc.first-election.timeout.min = 2s (fallback to
> raft.server.rpc.timeout.min) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> raft.server.rpc.first-election.timeout.max = 4s (fallback to
> raft.server.rpc.timeout.max) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272-LeaderElection17575 got exception when
> requesting votes: java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272-LeaderElection17575 got exception when
> requesting votes: java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272-LeaderElection17575: PRE_VOTE REJECTED
> received 0 response(s) and 2 exception(s): |
> org.apache.ratis.server.impl.LeaderElection (LeaderElection.java:89)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] | Exception 0:
> java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] | Exception 1:
> java.util.concurrent.ExecutionException:
> org.apache.ratis.thirdparty.io.grpc.StatusRuntimeException: UNAVAILABLE: io
> exception | org.apache.ratis.server.impl.LeaderElection (LogUtils.java:137)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272-LeaderElection17575 PRE_VOTE round 0:
> result REJECTED | org.apache.ratis.server.impl.LeaderElection
> (LeaderElection.java:309)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] |
> 8.5.226.6_22252@group-000100000272: changes role from CANDIDATE to FOLLOWER
> at term 9 for REJECTED | org.apache.ratis.server.RaftServer$Division
> (RaftServerImpl.java:317)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] | 8.5.226.6_22252:
> shutdown 8.5.226.6_22252@group-000100000272-LeaderElection17575 |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:130)
> 2022-09-21 11:46:32,213 | INFO |
> [8.5.226.6_22252@group-000100000272-LeaderElection17575] | 8.5.226.6_22252:
> start 8.5.226.6_22252@group-000100000272-FollowerState |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:139)
> 2022-09-21 11:46:32,214 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] |
> raft.server.rpc.first-election.timeout.min = 2s (fallback to
> raft.server.rpc.timeout.min) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,214 | INFO |
> [8.5.226.6_22252@group-000100000272-FollowerState] |
> raft.server.rpc.first-election.timeout.max = 4s (fallback to
> raft.server.rpc.timeout.max) | org.apache.ratis.server.RaftServerConfigKeys
> (ConfUtils.java:53)
> 2022-09-21 11:46:32,629 | INFO | [8.5.226.6_22252-impl-thread2] |
> Successfully read 121 entries from segment file
> /srv/BigData/iotdb/iotdbserver/data1/consensus/data_region/47474747-4747-4747-4747-000100000247/current/log_32728-32848
> | org.apache.ratis.server.raftlog.segmented.LogSegment (LogSegment.java:173)
> 2022-09-21 11:46:33,004 | INFO | [8.5.226.6_22252-impl-thread66] |
> Successfully read 121 entries from segment file
> /srv/BigData/iotdb/iotdbserver/data1/consensus/data_region/47474747-4747-4747-4747-000100000224/current/log_33431-33551
> | org.apache.ratis.server.raftlog.segmented.LogSegment (LogSegment.java:173)
> 2022-09-21 11:46:33,048 | INFO |
> [8.5.226.6_22252@group-000100000268-FollowerState] |
> 8.5.226.6_22252@group-000100000268-FollowerState: change to CANDIDATE,
> lastRpcElapsedTime:3739429092ns, electionTimeout:3739ms |
> org.apache.ratis.server.impl.FollowerState (FollowerState.java:143)
> 2022-09-21 11:46:33,048 | INFO |
> [8.5.226.6_22252@group-000100000268-FollowerState] | 8.5.226.6_22252:
> shutdown 8.5.226.6_22252@group-000100000268-FollowerState |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:110)
> 2022-09-21 11:46:33,048 | INFO |
> [8.5.226.6_22252@group-000100000268-FollowerState] |
> 8.5.226.6_22252@group-000100000268: changes role from FOLLOWER to CANDIDATE
> at term 6 for changeToCandidate | org.apache.ratis.server.RaftServer$Division
> (RaftServerImpl.java:317)
> 2022-09-21 11:46:33,049 | INFO |
> [8.5.226.6_22252@group-000100000268-FollowerState] |
> raft.server.leaderelection.pre-vote = true (default) |
> org.apache.ratis.server.RaftServerConfigKeys (ConfUtils.java:46)
> 2022-09-21 11:46:33,049 | INFO |
> [8.5.226.6_22252@group-000100000268-FollowerState] | 8.5.226.6_22252: start
> 8.5.226.6_22252@group-000100000268-LeaderElection17576 |
> org.apache.ratis.server.impl.RoleInfo (RoleInfo.java:139)
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
