You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@ignite.apache.org by "Alexey Scherbakov (Jira)" <ji...@apache.org> on 2021/08/09 09:18:00 UTC

[jira] [Updated] (IGNITE-15271) ITNodeTest.testFollowerStartStopFollowing is still flaky

     [ https://issues.apache.org/jira/browse/IGNITE-15271?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Alexey Scherbakov updated IGNITE-15271:
---------------------------------------
    Description: 
{noformat}
org.opentest4j.AssertionFailedError
org.opentest4j.AssertionFailedError: expected: <true> but was: <false>
  at org.apache.ignite.raft.jraft.core.ITNodeTest.testFollowerStartStopFollowing(ITNodeTest.java:2678)
{noformat}
The issue is caused by (unexpected) excessive number of state changes during leader election on term=2:
{noformat}
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStopFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[EVOTEFORCANDIDATE<10010>: Raft node votes for some candidate, step down to restart election_timer.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
WARNING: Node <unitest/172.17.0.6:5004> received invalid RequestVoteResponse from 172.17.0.6:5006, state not in STATE_CANDIDATE but STATE_LEADER.
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
{noformat}

  was:

{noformat}
org.opentest4j.AssertionFailedError
org.opentest4j.AssertionFailedError: expected: <true> but was: <false>
  at org.apache.ignite.raft.jraft.core.ITNodeTest.testFollowerStartStopFollowing(ITNodeTest.java:2678)
{noformat}

The issue is related to excessive number of state changes during leader election on term=2:

{noformat}
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStopFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[EVOTEFORCANDIDATE<10010>: Raft node votes for some candidate, step down to restart election_timer.]].
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
WARNING: Node <unitest/172.17.0.6:5004> received invalid RequestVoteResponse from 172.17.0.6:5006, state not in STATE_CANDIDATE but STATE_LEADER.
Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
{noformat}





> ITNodeTest.testFollowerStartStopFollowing is still flaky
> --------------------------------------------------------
>
>                 Key: IGNITE-15271
>                 URL: https://issues.apache.org/jira/browse/IGNITE-15271
>             Project: Ignite
>          Issue Type: Bug
>    Affects Versions: 3.0.0-alpha2
>            Reporter: Alexey Scherbakov
>            Assignee: Alexey Scherbakov
>            Priority: Major
>             Fix For: 3.0.0-alpha3
>
>
> {noformat}
> org.opentest4j.AssertionFailedError
> org.opentest4j.AssertionFailedError: expected: <true> but was: <false>
>   at org.apache.ignite.raft.jraft.core.ITNodeTest.testFollowerStartStopFollowing(ITNodeTest.java:2678)
> {noformat}
> The issue is caused by (unexpected) excessive number of state changes during leader election on term=2:
> {noformat}
> INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> INFO: onStopFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[EVOTEFORCANDIDATE<10010>: Raft node votes for some candidate, step down to restart election_timer.]].
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> WARNING: Node <unitest/172.17.0.6:5004> received invalid RequestVoteResponse from 172.17.0.6:5006, state not in STATE_CANDIDATE but STATE_LEADER.
> Aug 09, 2021 9:32:37 AM org.apache.ignite.lang.IgniteLogger logInternal
> INFO: onStartFollowing: LeaderChangeContext [leaderId=172.17.0.6:5004, term=2, status=Status[ENEWLEADER<10011>: Follower receives message from new leader with the same term.]].
> {noformat}



--
This message was sent by Atlassian Jira
(v8.3.4#803005)