[ZOOKEEPER-3201] Flaky test: org.apache.zookeeper.server.ZxidRolloverTest.testRolloverThenLeaderRestart

• Type: Sub-task
• Status: Open
• Priority: Major
• Resolution: Unresolved
• Assignee: Unassigned
• Reporter: Michael K. Edwards
• Created: 25/Nov/18 22:34
• Updated: 26/Nov/18 06:46

Description

Encountered when running tests locally:

64429     [junit] 2018-11-25 22:28:12,729 [myid:127.0.0.1:27389] - INFO  [main-SendThread(127.0.0.1:27389):ClientCnxn$SendThread@1108] - Opening socket connection to server localhost/127.0.0      .1:27389. Will not attempt to authenticate using SASL (unknown error)

64430     [junit] 2018-11-25 22:28:12,730 [myid:127.0.0.1:27389] - INFO  [main-SendThread(127.0.0.1:27389):ClientCnxn$SendThread@955] - Socket connection established, initiating session, cli      ent: /127.0.0.1:47668, server: localhost/127.0.0.1:27389

64431     [junit] 2018-11-25 22:28:12,734 [myid:] - INFO  [NIOWorkerThread-1:Learner@117] - Revalidating client: 0x10000a9cccf0000

64432     [junit] 2018-11-25 22:28:12,743 [myid:127.0.0.1:27389] - INFO  [main-SendThread(127.0.0.1:27389):ClientCnxn$SendThread@1390] - Session establishment complete on server localhost/12      7.0.0.1:27389, sessionid = 0x10000a9cccf0000, negotiated timeout = 30000

64433     [junit] 2018-11-25 22:28:13,009 [myid:127.0.0.1:27392] - INFO  [main-SendThread(127.0.0.1:27392):ClientCnxn$SendThread@1108] - Opening socket connection to server localhost/127.0.0      .1:27392. Will not attempt to authenticate using SASL (unknown error)

64434     [junit] 2018-11-25 22:28:13,009 [myid:127.0.0.1:27392] - INFO  [main-SendThread(127.0.0.1:27392):ClientCnxn$SendThread@955] - Socket connection established, initiating session, cli      ent: /127.0.0.1:52160, server: localhost/127.0.0.1:27392

64435     [junit] 2018-11-25 22:28:13,016 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@1108] - Opening socket connection to server localhost/127.0.0      .1:27395. Will not attempt to authenticate using SASL (unknown error)

64436     [junit] 2018-11-25 22:28:13,016 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@955] - Socket connection established, initiating session, cli      ent: /127.0.0.1:47256, server: localhost/127.0.0.1:27395

64437     [junit] 2018-11-25 22:28:13,017 [myid:] - INFO  [NIOWorkerThread-4:ZooKeeperServer@1030] - Refusing session request for client /127.0.0.1:47256 as it has seen zxid 0x300000000 our       last zxid is 0x2fffffffe client must try another server

64438     [junit] 2018-11-25 22:28:13,018 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@1236] - Unable to read additional data from server sessionid       0x30000a9ccd20000, likely server has closed socket, closing socket connection and attempting reconnect

64439     [junit] 2018-11-25 22:28:13,023 [myid:127.0.0.1:27392] - INFO  [main-SendThread(127.0.0.1:27392):ClientCnxn$SendThread@1390] - Session establishment complete on server localhost/12      7.0.0.1:27392, sessionid = 0x20000a9d0940000, negotiated timeout = 30000

64440     [junit] 2018-11-25 22:28:13,119 [myid:] - INFO  [main:FourLetterWordMain@87] - connecting to 127.0.0.1 27395

64441     [junit] 2018-11-25 22:28:13,120 [myid:] - INFO  [NIOWorkerThread-1:NIOServerCnxn@518] - Processing stat command from /127.0.0.1:47258

64442     [junit] 2018-11-25 22:28:13,121 [myid:] - INFO  [NIOWorkerThread-1:StatCommand@53] - Stat command output

64443     [junit] 2018-11-25 22:28:14,134 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@1108] - Opening socket connection to server localhost/127.0.0      .1:27395. Will not attempt to authenticate using SASL (unknown error)

64444     [junit] 2018-11-25 22:28:14,135 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@955] - Socket connection established, initiating session, cli      ent: /127.0.0.1:47312, server: localhost/127.0.0.1:27395

64445     [junit] 2018-11-25 22:28:14,135 [myid:] - INFO  [NIOWorkerThread-2:ZooKeeperServer@1030] - Refusing session request for client /127.0.0.1:47312 as it has seen zxid 0x300000000 our       last zxid is 0x2fffffffe client must try another server

64446     [junit] 2018-11-25 22:28:14,137 [myid:127.0.0.1:27395] - INFO  [main-SendThread(127.0.0.1:27395):ClientCnxn$SendThread@1236] - Unable to read additional data from server sessionid       0x30000a9ccd20000, likely server has closed socket, closing socket connection and attempting reconnect

64447     [junit] 2018-11-25 22:28:14,240 [myid:] - INFO  [main:JUnit4ZKTestRunner$LoggedInvokeMethod@98] - TEST METHOD FAILED testRolloverThenLeaderRestart

64448     [junit] org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /foofoofoo-connected

64449     [junit]     at org.apache.zookeeper.KeeperException.create(KeeperException.java:102)

64450     [junit]     at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)

64451     [junit]     at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1942)

64452     [junit]     at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1970)

64453     [junit]     at org.apache.zookeeper.server.ZxidRolloverTest.checkClientConnected(ZxidRolloverTest.java:119)

64454     [junit]     at org.apache.zookeeper.server.ZxidRolloverTest.checkClientsConnected(ZxidRolloverTest.java:90)

64455     [junit]     at org.apache.zookeeper.server.ZxidRolloverTest.start(ZxidRolloverTest.java:165)

64456     [junit]     at org.apache.zookeeper.server.ZxidRolloverTest.testRolloverThenLeaderRestart(ZxidRolloverTest.java:382)