See https://builds.apache.org/job/ZooKeeper-trunk-WinVS2008_java/715/
################################################################################### ########################## LAST 60 LINES OF THE CONSOLE ########################### [...truncated 246132 lines...] [junit] 2014-03-23 10:56:29,824 [myid:] - INFO [main:ClientBase@443] - STARTING server [junit] 2014-03-23 10:56:29,824 [myid:] - INFO [main:ClientBase@364] - CREATING server instance 127.0.0.1:11221 [junit] 2014-03-23 10:56:29,824 [myid:] - INFO [main:NIOServerCnxnFactory@670] - Configuring NIO connection handler with 10s sessionless connection timeout, 1 selector thread(s), 4 worker threads, and 64 kB direct buffers. [junit] 2014-03-23 10:56:29,825 [myid:] - INFO [main:NIOServerCnxnFactory@683] - binding to port 0.0.0.0/0.0.0.0:11221 [junit] 2014-03-23 10:56:29,826 [myid:] - INFO [main:ClientBase@339] - STARTING server instance 127.0.0.1:11221 [junit] 2014-03-23 10:56:29,827 [myid:] - INFO [main:ZooKeeperServer@149] - Created server with tickTime 3000 minSessionTimeout 6000 maxSessionTimeout 60000 datadir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1773611844150672273.junit.dir\version-2 snapdir f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1773611844150672273.junit.dir\version-2 [junit] 2014-03-23 10:56:29,827 [myid:] - INFO [main:FileSnap@83] - Reading snapshot f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1773611844150672273.junit.dir\version-2\snapshot.b [junit] 2014-03-23 10:56:29,829 [myid:] - INFO [main:FileTxnSnapLog@298] - Snapshotting: 0xb to f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build\test\tmp\test1773611844150672273.junit.dir\version-2\snapshot.b [junit] 2014-03-23 10:56:29,831 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221 [junit] 2014-03-23 10:56:30,000 [myid:] - INFO [SessionTracker:SessionTrackerImpl@135] - SessionTrackerImpl exited loop! [junit] 2014-03-23 10:56:30,172 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:65363 [junit] 2014-03-23 10:56:30,356 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@835] - Processing stat command from /127.0.0.1:65363 [junit] 2014-03-23 10:56:30,356 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn$StatCommand@684] - Stat command output [junit] 2014-03-23 10:56:30,357 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:65363 (no session established for client) [junit] 2014-03-23 10:56:30,357 [myid:] - INFO [main:JMXEnv@224] - ensureParent:[InMemoryDataTree, StandaloneServer_port] [junit] 2014-03-23 10:56:30,359 [myid:] - INFO [main:JMXEnv@241] - expect:InMemoryDataTree [junit] 2014-03-23 10:56:30,359 [myid:] - INFO [main:JMXEnv@245] - found:InMemoryDataTree org.apache.ZooKeeperService:name0=StandaloneServer_port-1,name1=InMemoryDataTree [junit] 2014-03-23 10:56:30,359 [myid:] - INFO [main:JMXEnv@241] - expect:StandaloneServer_port [junit] 2014-03-23 10:56:30,360 [myid:] - INFO [main:JMXEnv@245] - found:StandaloneServer_port org.apache.ZooKeeperService:name0=StandaloneServer_port-1 [junit] 2014-03-23 10:56:30,360 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@55] - Memory used 4953 [junit] 2014-03-23 10:56:30,360 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@60] - Number of threads 22 [junit] 2014-03-23 10:56:30,360 [myid:] - INFO [main:JUnit4ZKTestRunner$LoggedInvokeMethod@65] - FINISHED TEST METHOD testQuota [junit] 2014-03-23 10:56:30,360 [myid:] - INFO [main:ClientBase@520] - tearDown starting [junit] 2014-03-23 10:56:30,667 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1093] - Opening socket connection to server 127.0.0.1/127.0.0.1:11221. Will not attempt to authenticate using SASL (unknown error) [junit] 2014-03-23 10:56:30,667 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@963] - Socket connection established to 127.0.0.1/127.0.0.1:11221, initiating session [junit] 2014-03-23 10:56:30,668 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@296] - Accepted socket connection from /127.0.0.1:65364 [junit] 2014-03-23 10:56:30,902 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@858] - Client attempting to renew session 0x144ee9579a40000 at /127.0.0.1:65364 [junit] 2014-03-23 10:56:30,903 [myid:] - INFO [NIOWorkerThread-2:ZooKeeperServer@604] - Established session 0x144ee9579a40000 with negotiated timeout 30000 for client /127.0.0.1:65364 [junit] 2014-03-23 10:56:30,932 [myid:] - INFO [main-SendThread(127.0.0.1:11221):ClientCnxn$SendThread@1346] - Session establishment complete on server 127.0.0.1/127.0.0.1:11221, sessionid = 0x144ee9579a40000, negotiated timeout = 30000 [junit] 2014-03-23 10:56:31,150 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@679] - Processed session termination for sessionid: 0x144ee9579a40000 [junit] 2014-03-23 10:56:31,150 [myid:] - INFO [SyncThread:0:FileTxnLog@200] - Creating new log file: log.c [junit] 2014-03-23 10:56:31,185 [myid:] - INFO [main:ZooKeeper@954] - Session: 0x144ee9579a40000 closed [junit] 2014-03-23 10:56:31,185 [myid:] - INFO [main:ClientBase@490] - STOPPING server [junit] 2014-03-23 10:56:31,186 [myid:] - INFO [ConnnectionExpirer:NIOServerCnxnFactory$ConnectionExpirerThread@583] - ConnnectionExpirerThread interrupted [junit] 2014-03-23 10:56:31,186 [myid:] - INFO [main-EventThread:ClientCnxn$EventThread@529] - EventThread shut down [junit] 2014-03-23 10:56:31,188 [myid:] - INFO [NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:11221:NIOServerCnxnFactory$AcceptThread@219] - accept thread exitted run method [junit] 2014-03-23 10:56:31,188 [myid:] - INFO [NIOServerCxnFactory.SelectorThread-0:NIOServerCnxnFactory$SelectorThread@420] - selector thread exitted run method [junit] 2014-03-23 10:56:31,188 [myid:] - INFO [NIOWorkerThread-1:NIOServerCnxn@1006] - Closed socket connection for client /127.0.0.1:65364 which had sessionid 0x144ee9579a40000 [junit] 2014-03-23 10:56:31,188 [myid:] - INFO [main:ZooKeeperServer@428] - shutting down [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [main:SessionTrackerImpl@184] - Shutting down [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [main:PrepRequestProcessor@974] - Shutting down [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [main:SyncRequestProcessor@191] - Shutting down [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [ProcessThread(sid:0 cport:-1)::PrepRequestProcessor@155] - PrepRequestProcessor exited loop! [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [SyncThread:0:SyncRequestProcessor@169] - SyncRequestProcessor exited! [junit] 2014-03-23 10:56:31,189 [myid:] - INFO [main:FinalRequestProcessor@454] - shutdown of request processor complete [junit] 2014-03-23 10:56:31,190 [myid:] - INFO [main:FourLetterWordMain@43] - connecting to 127.0.0.1 11221 [junit] 2014-03-23 10:56:32,237 [myid:] - INFO [main:JMXEnv@142] - ensureOnly:[] [junit] 2014-03-23 10:56:32,241 [myid:] - INFO [main:ZKTestCase$1@61] - SUCCEEDED testQuota [junit] 2014-03-23 10:56:32,241 [myid:] - INFO [main:ZKTestCase$1@56] - FINISHED testQuota [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 15.884 sec BUILD FAILED f:\hudson\hudson-slave\workspace\ZooKeeper-trunk-WinVS2008_java\trunk\build.xml:1315: Tests failed! Total time: 124 minutes 58 seconds Build step 'Invoke Ant' marked build as failure Recording test results Email was triggered for: Failure Sending email for trigger: Failure ################################################################################### ############################## FAILED TESTS (if any) ############################## 23 tests failed. REGRESSION: org.apache.zookeeper.RemoveWatchesTest.testRemoveWatcherWhenNoConnection[0] Error Message: KeeperErrorCode = Session expired for /node1 Stack Trace: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /node1 at org.apache.zookeeper.KeeperException.create(KeeperException.java:131) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.removeWatches(ZooKeeper.java:2368) at org.apache.zookeeper.RemoveWatchesTest.removeWatches(RemoveWatchesTest.java:96) at org.apache.zookeeper.RemoveWatchesTest.testRemoveWatcherWhenNoConnection(RemoveWatchesTest.java:483) REGRESSION: org.apache.zookeeper.server.InvalidSnapshotTest.testInvalidSnapshot Error Message: expected [0x144ee2a9aec0000] expected:<1> but was:<0> Stack Trace: junit.framework.AssertionFailedError: expected [0x144ee2a9aec0000] expected:<1> but was:<0> at org.apache.zookeeper.test.JMXEnv.ensureAll(JMXEnv.java:124) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:196) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:176) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:161) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:154) at org.apache.zookeeper.server.InvalidSnapshotTest.testInvalidSnapshot(InvalidSnapshotTest.java:53) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.server.ZooKeeperServerMainTest.testStandalone Error Message: waiting for server being up Stack Trace: junit.framework.AssertionFailedError: waiting for server being up at org.apache.zookeeper.server.ZooKeeperServerMainTest.testStandalone(ZooKeeperServerMainTest.java:138) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.server.quorum.CommitProcessorTest.testOneCommitWorker Error Message: No read requests processed Stack Trace: junit.framework.AssertionFailedError: No read requests processed at org.apache.zookeeper.server.quorum.CommitProcessorTest.checkProcessedRequest(CommitProcessorTest.java:200) at org.apache.zookeeper.server.quorum.CommitProcessorTest.testOneCommitWorker(CommitProcessorTest.java:184) REGRESSION: org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testHighestZxidJoinLate Error Message: Waiting too long Stack Trace: java.lang.RuntimeException: Waiting too long at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.waitForOne(QuorumPeerMainTest.java:315) at org.apache.zookeeper.server.quorum.QuorumPeerMainTest.testHighestZxidJoinLate(QuorumPeerMainTest.java:279) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig Error Message: KeeperErrorCode = NoNode for /zookeeper/config Stack Trace: org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /zookeeper/config at org.apache.zookeeper.KeeperException.create(KeeperException.java:115) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.getConfig(ZooKeeper.java:1703) at org.apache.zookeeper.ZooKeeper.getConfig(ZooKeeper.java:1758) at org.apache.zookeeper.test.ReconfigTest.testServerHasConfig(ReconfigTest.java:100) at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testCurrentServersAreObserversInNextConfig(ReconfigRecoveryTest.java:196) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.AsyncHammerTest.testObserversHammer Error Message: waiting for server up Stack Trace: junit.framework.AssertionFailedError: waiting for server up at org.apache.zookeeper.test.QuorumBase.startServers(QuorumBase.java:221) at org.apache.zookeeper.test.QuorumBase.setUp(QuorumBase.java:118) at org.apache.zookeeper.test.AsyncHammerTest.setUp(AsyncHammerTest.java:52) at org.apache.zookeeper.test.AsyncHammerTest.testObserversHammer(AsyncHammerTest.java:203) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.ClientHammerTest.testHammerBasic Error Message: expected [0x144ee4426d80000] expected:<1> but was:<0> Stack Trace: junit.framework.AssertionFailedError: expected [0x144ee4426d80000] expected:<1> but was:<0> at org.apache.zookeeper.test.JMXEnv.ensureAll(JMXEnv.java:124) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:196) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:176) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:161) at org.apache.zookeeper.test.ClientBase.createClient(ClientBase.java:154) at org.apache.zookeeper.test.ClientHammerTest.runHammer(ClientHammerTest.java:129) at org.apache.zookeeper.test.ClientHammerTest.testHammerBasic(ClientHammerTest.java:119) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.FLETest.testJoin Error Message: Incorrect LEADING state for peer 2 Stack Trace: junit.framework.AssertionFailedError: Incorrect LEADING state for peer 2 at org.apache.zookeeper.test.FLETest.testJoin(FLETest.java:453) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.FollowerResyncConcurrencyTest.testResyncByTxnlogThenDiffAfterFollowerCrashes Error Message: Waiting for server down Stack Trace: junit.framework.AssertionFailedError: Waiting for server down at org.apache.zookeeper.test.QuorumUtil.shutdownAll(QuorumUtil.java:241) at org.apache.zookeeper.test.FollowerResyncConcurrencyTest.followerResyncCrashTest(FollowerResyncConcurrencyTest.java:361) at org.apache.zookeeper.test.FollowerResyncConcurrencyTest.testResyncByTxnlogThenDiffAfterFollowerCrashes(FollowerResyncConcurrencyTest.java:191) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.LeaderSessionTrackerTest.testExpiredSessionWithLocalSession Error Message: Waiting for server down Stack Trace: junit.framework.AssertionFailedError: Waiting for server down at org.apache.zookeeper.test.QuorumUtil.shutdownAll(QuorumUtil.java:241) at org.apache.zookeeper.test.LeaderSessionTrackerTest.tearDown(LeaderSessionTrackerTest.java:66) REGRESSION: org.apache.zookeeper.test.LoadFromLogTest.testRestoreWithTransactionErrors Error Message: KeeperErrorCode = ConnectionLoss for /invaliddir/test- Stack Trace: org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode = ConnectionLoss for /invaliddir/test- at org.apache.zookeeper.KeeperException.create(KeeperException.java:99) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:1053) at org.apache.zookeeper.test.LoadFromLogTest.testRestoreWithTransactionErrors(LoadFromLogTest.java:451) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.LoadFromLogTest.testLoadFailure Error Message: waiting for server to shutdown Stack Trace: junit.framework.AssertionFailedError: waiting for server to shutdown at org.apache.zookeeper.test.LoadFromLogTest.testLoadFailure(LoadFromLogTest.java:163) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.LoadFromLogTest.testDatadirAutocreate Error Message: waiting for server being down Stack Trace: junit.framework.AssertionFailedError: waiting for server being down at org.apache.zookeeper.test.LoadFromLogTest.testDatadirAutocreate(LoadFromLogTest.java:500) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) REGRESSION: org.apache.zookeeper.test.LoadFromLogTest.testLoad Error Message: waiting for server to shutdown Stack Trace: junit.framework.AssertionFailedError: waiting for server to shutdown at org.apache.zookeeper.test.LoadFromLogTest.testLoad(LoadFromLogTest.java:102) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.server.ZxidRolloverTest.testMultipleRollover Error Message: Waiting for server down Stack Trace: java.lang.AssertionError: Waiting for server down at org.apache.zookeeper.test.QuorumUtil.shutdownAll(QuorumUtil.java:241) at org.apache.zookeeper.server.ZxidRolloverTest.tearDown(ZxidRolloverTest.java:217) FAILED: org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startSingleServerTest Error Message: Error- Server started in Standalone Mode! Stack Trace: junit.framework.AssertionFailedError: Error- Server started in Standalone Mode! at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startServer(StandaloneDisabledTest.java:161) at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startFollowers(StandaloneDisabledTest.java:187) at org.apache.zookeeper.server.quorum.StandaloneDisabledTest.startSingleServerTest(StandaloneDisabledTest.java:68) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testNextConfigUnreachable Error Message: QP failed to shutdown in 30 seconds: QuorumPeer[myid=0]/127.0.0.1:11251 Stack Trace: junit.framework.AssertionFailedError: QP failed to shutdown in 30 seconds: QuorumPeer[myid=0]/127.0.0.1:11251 at org.apache.zookeeper.test.QuorumBase.shutdown(QuorumBase.java:393) at org.apache.zookeeper.server.quorum.QuorumPeerTestBase$TestQPMain.shutdown(QuorumPeerTestBase.java:52) at org.apache.zookeeper.server.quorum.QuorumPeerTestBase$MainThread.shutdown(QuorumPeerTestBase.java:152) at org.apache.zookeeper.server.quorum.ReconfigRecoveryTest.testNextConfigUnreachable(ReconfigRecoveryTest.java:268) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.test.WatcherTest.testWatchAutoResetWithPending Error Message: Did not disconnect Stack Trace: java.util.concurrent.TimeoutException: Did not disconnect at org.apache.zookeeper.test.ClientBase$CountdownWatcher.waitForDisconnected(ClientBase.java:145) at org.apache.zookeeper.test.WatcherTest.testWatchAutoResetWithPending(WatcherTest.java:201) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment Error Message: Not following Stack Trace: junit.framework.AssertionFailedError: Not following at org.apache.zookeeper.test.QuorumTest.testNoLogBeforeLeaderEstablishment(QuorumTest.java:408) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch Error Message: First leader election failed Stack Trace: junit.framework.AssertionFailedError: First leader election failed at org.apache.zookeeper.test.FLENewEpochTest.testLENewEpoch(FLENewEpochTest.java:177) at org.apache.zookeeper.JUnit4ZKTestRunner$LoggedInvokeMethod.evaluate(JUnit4ZKTestRunner.java:52) FAILED: org.apache.zookeeper.server.util.DynamicConfigBCTest.dynamicConfigBackwardCompatibilityTest Error Message: null Stack Trace: junit.framework.AssertionFailedError at org.apache.zookeeper.server.util.DynamicConfigBCTest.dynamicConfigBackwardCompatibilityTest(DynamicConfigBCTest.java:141) FAILED: org.apache.zookeeper.test.StaticHostProviderTest.testUpdateClientMigrateOrNot Error Message: Timeout occurred. Please note the time in the report does not reflect the time until the timeout. Stack Trace: junit.framework.AssertionFailedError: Timeout occurred. Please note the time in the report does not reflect the time until the timeout.